{"level":"warn","ts":"2026-03-24T14:48:31.766967Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:48:31.767046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54: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.19.213.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-24T14:48:31.767108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:48:31.767119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-03-24T14:48:31.767136Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-24T14:48:31.76773Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-03-24T14:48:31.767812Z","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.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-24T14:48:31.772855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.824955ms"} {"level":"info","ts":"2026-03-24T14:48:31.78403Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-03-24T14:48:31.784108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-03-24T14:48:31.784133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-03-24T14:48:31.78415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-24T14:48:31.784163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-03-24T14:48:31.784226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-03-24T14:48:31.792026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-24T14:48:31.79369Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-24T14:48:31.794947Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-24T14:48:31.797025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-24T14:48:31.797729Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-24T14:48:31.797797Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-24T14:48:31.797862Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-24T14:48:31.797876Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-24T14:48:31.804945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-03-24T14:48:31.805075Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-03-24T14:48:31.806212Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-24T14:48:31.806485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-24T14:48:31.806525Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-24T14:48:31.806555Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-03-24T14:48:31.806588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-03-24T14:48:32.485255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-03-24T14:48:32.485312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-24T14:48:32.485352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-03-24T14:48:32.485376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-03-24T14:48:32.485387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-03-24T14:48:32.485402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-03-24T14:48:32.485415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-03-24T14:48:32.486535Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:48:32.487542Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:48:32.487545Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-03-24T14:48:32.487684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:48:32.48783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-24T14:48:32.487875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-24T14:48:32.489175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-03-24T14:48:32.490786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-24T14:48:32.502897Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:48:32.503023Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:48:32.503069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:48:37.292783Z","caller":"traceutil/trace.go:171","msg":"trace[839895744] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"450.447654ms","start":"2026-03-24T14:48:36.842293Z","end":"2026-03-24T14:48:37.29274Z","steps":["trace[839895744] 'process raft request' (duration: 450.192596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:37.294326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:36.842272Z","time spent":"450.667413ms","remote":"127.0.0.1:39350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:48:38.583526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.423938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:48:38.58374Z","caller":"traceutil/trace.go:171","msg":"trace[1693735408] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.130570551s","start":"2026-03-24T14:48:37.453153Z","end":"2026-03-24T14:48:38.583724Z","steps":["trace[1693735408] 'process raft request' (duration: 1.130499251s)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:38.583739Z","caller":"traceutil/trace.go:171","msg":"trace[466520643] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"1.731250535s","start":"2026-03-24T14:48:36.852447Z","end":"2026-03-24T14:48:38.583698Z","steps":["trace[466520643] 'process raft request' (duration: 1.15142769s)","trace[466520643] 'compare' (duration: 577.978747ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:38.583826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.453131Z","time spent":"1.13066842s","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:48:38.583891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:36.852426Z","time spent":"1.731385064s","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:48:38.583959Z","caller":"traceutil/trace.go:171","msg":"trace[473679235] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"1.553645826s","start":"2026-03-24T14:48:37.030111Z","end":"2026-03-24T14:48:38.583757Z","steps":["trace[473679235] 'read index received' (duration: 262.399956ms)","trace[473679235] 'applied index is now lower than readState.Index' (duration: 1.29124334s)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:38.58414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553991725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"warn","ts":"2026-03-24T14:48:38.584204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.325706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-24T14:48:38.584235Z","caller":"traceutil/trace.go:171","msg":"trace[12592743] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:272; }","duration":"1.554141934s","start":"2026-03-24T14:48:37.030071Z","end":"2026-03-24T14:48:38.584213Z","steps":["trace[12592743] 'agreement among raft nodes before linearized reading' (duration: 1.553932865s)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:38.584265Z","caller":"traceutil/trace.go:171","msg":"trace[1168926306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:272; }","duration":"634.388866ms","start":"2026-03-24T14:48:37.94986Z","end":"2026-03-24T14:48:38.584249Z","steps":["trace[1168926306] 'agreement among raft nodes before linearized reading' (duration: 634.288136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:38.584281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.030048Z","time spent":"1.554224663s","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-24T14:48:38.584286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.214651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-24T14:48:38.584306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.949805Z","time spent":"634.493955ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-24T14:48:38.584327Z","caller":"traceutil/trace.go:171","msg":"trace[478052973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:272; }","duration":"638.25945ms","start":"2026-03-24T14:48:37.946056Z","end":"2026-03-24T14:48:38.584315Z","steps":["trace[478052973] 'agreement among raft nodes before linearized reading' (duration: 638.171581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:38.584277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282374727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:38.584422Z","caller":"traceutil/trace.go:171","msg":"trace[2078540792] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:272; }","duration":"1.282617036s","start":"2026-03-24T14:48:37.301782Z","end":"2026-03-24T14:48:38.584399Z","steps":["trace[2078540792] 'agreement among raft nodes before linearized reading' (duration: 1.282350087s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:38.584496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.30177Z","time spent":"1.282711515s","remote":"127.0.0.1:39350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-03-24T14:48:38.584365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.945991Z","time spent":"638.36712ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:38.58456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.417417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:38.584599Z","caller":"traceutil/trace.go:171","msg":"trace[434497160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:272; }","duration":"729.489796ms","start":"2026-03-24T14:48:37.855096Z","end":"2026-03-24T14:48:38.584586Z","steps":["trace[434497160] 'agreement among raft nodes before linearized reading' (duration: 729.439296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:38.584635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:37.855071Z","time spent":"729.558065ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:48:40.564482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.781817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-24T14:48:40.564676Z","caller":"traceutil/trace.go:171","msg":"trace[2061993604] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:333; }","duration":"224.978976ms","start":"2026-03-24T14:48:40.339649Z","end":"2026-03-24T14:48:40.564628Z","steps":["trace[2061993604] 'range keys from in-memory index tree' (duration: 224.707437ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:09.030018Z","caller":"traceutil/trace.go:171","msg":"trace[109427348] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"282.453004ms","start":"2026-03-24T14:49:08.747515Z","end":"2026-03-24T14:49:09.029968Z","steps":["trace[109427348] 'process raft request' (duration: 282.260543ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:09.085453Z","caller":"traceutil/trace.go:171","msg":"trace[1317011129] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"337.736644ms","start":"2026-03-24T14:49:08.747684Z","end":"2026-03-24T14:49:09.085421Z","steps":["trace[1317011129] 'read index received' (duration: 282.045811ms)","trace[1317011129] 'applied index is now lower than readState.Index' (duration: 55.689463ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:09.085606Z","caller":"traceutil/trace.go:171","msg":"trace[1087231553] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"338.037936ms","start":"2026-03-24T14:49:08.747553Z","end":"2026-03-24T14:49:09.085591Z","steps":["trace[1087231553] 'process raft request' (duration: 337.726174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:09.085826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.252579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-24T14:49:09.085918Z","caller":"traceutil/trace.go:171","msg":"trace[1728051433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"313.38307ms","start":"2026-03-24T14:49:08.772517Z","end":"2026-03-24T14:49:09.0859Z","steps":["trace[1728051433] 'agreement among raft nodes before linearized reading' (duration: 313.219719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:09.085943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:08.747537Z","time spent":"338.120276ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:09.085944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.242827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-24T14:49:09.085984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:08.772503Z","time spent":"313.47271ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-24T14:49:09.086006Z","caller":"traceutil/trace.go:171","msg":"trace[481374615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"338.315557ms","start":"2026-03-24T14:49:08.747674Z","end":"2026-03-24T14:49:09.085989Z","steps":["trace[481374615] 'agreement among raft nodes before linearized reading' (duration: 338.185186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:09.085998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.775245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-03-24T14:49:09.086053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:08.747587Z","time spent":"338.458579ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-24T14:49:09.086106Z","caller":"traceutil/trace.go:171","msg":"trace[478826233] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:525; }","duration":"228.927336ms","start":"2026-03-24T14:49:08.857155Z","end":"2026-03-24T14:49:09.086082Z","steps":["trace[478826233] 'agreement among raft nodes before linearized reading' (duration: 228.708774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:11.379558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.695668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:49:11.379757Z","caller":"traceutil/trace.go:171","msg":"trace[2027602690] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"239.24218ms","start":"2026-03-24T14:49:11.14047Z","end":"2026-03-24T14:49:11.379712Z","steps":["trace[2027602690] 'process raft request' (duration: 89.2798ms)","trace[2027602690] 'compare' (duration: 149.497627ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:11.454898Z","caller":"traceutil/trace.go:171","msg":"trace[861798806] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"314.097363ms","start":"2026-03-24T14:49:11.140769Z","end":"2026-03-24T14:49:11.454866Z","steps":["trace[861798806] 'process raft request' (duration: 313.985412ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:11.455008Z","caller":"traceutil/trace.go:171","msg":"trace[1965275902] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"314.280665ms","start":"2026-03-24T14:49:11.140682Z","end":"2026-03-24T14:49:11.454963Z","steps":["trace[1965275902] 'process raft request' (duration: 313.895332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:11.455216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:11.14065Z","time spent":"314.450497ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:11.455722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:11.140743Z","time spent":"314.891708ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:15.320963Z","caller":"traceutil/trace.go:171","msg":"trace[1044647424] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"157.619577ms","start":"2026-03-24T14:49:15.163319Z","end":"2026-03-24T14:49:15.320938Z","steps":["trace[1044647424] 'read index received' (duration: 148.115953ms)","trace[1044647424] 'applied index is now lower than readState.Index' (duration: 9.503124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:49:15.321059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.731067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:49:15.32108Z","caller":"traceutil/trace.go:171","msg":"trace[1069790246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"157.813078ms","start":"2026-03-24T14:49:15.163259Z","end":"2026-03-24T14:49:15.321072Z","steps":["trace[1069790246] 'agreement among raft nodes before linearized reading' (duration: 157.761567ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:15.321052Z","caller":"traceutil/trace.go:171","msg":"trace[1491643241] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"165.518965ms","start":"2026-03-24T14:49:15.155495Z","end":"2026-03-24T14:49:15.321014Z","steps":["trace[1491643241] 'process raft request' (duration: 155.952921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:17.66616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609446992058592469,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-24T14:49:17.874599Z","caller":"traceutil/trace.go:171","msg":"trace[1040767297] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"709.166208ms","start":"2026-03-24T14:49:17.165407Z","end":"2026-03-24T14:49:17.874573Z","steps":["trace[1040767297] 'read index received' (duration: 708.811214ms)","trace[1040767297] 'applied index is now lower than readState.Index' (duration: 353.974µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:49:17.874776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.317969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:49:17.874813Z","caller":"traceutil/trace.go:171","msg":"trace[1567926807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"709.432611ms","start":"2026-03-24T14:49:17.165371Z","end":"2026-03-24T14:49:17.874804Z","steps":["trace[1567926807] 'agreement among raft nodes before linearized reading' (duration: 709.31844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:17.874843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.165352Z","time spent":"709.484381ms","remote":"127.0.0.1:39150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:49:17.874933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.277604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-24T14:49:17.874933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.279023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-24T14:49:17.875013Z","caller":"traceutil/trace.go:171","msg":"trace[1848811048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"549.418204ms","start":"2026-03-24T14:49:17.325573Z","end":"2026-03-24T14:49:17.874991Z","steps":["trace[1848811048] 'agreement among raft nodes before linearized reading' (duration: 549.180802ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:17.875023Z","caller":"traceutil/trace.go:171","msg":"trace[824848397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"358.428095ms","start":"2026-03-24T14:49:17.516578Z","end":"2026-03-24T14:49:17.875006Z","steps":["trace[824848397] 'agreement among raft nodes before linearized reading' (duration: 358.195412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:17.875033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.150838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-24T14:49:17.875053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.325544Z","time spent":"549.501325ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:49:17.875064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.516545Z","time spent":"358.510425ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-24T14:49:17.875084Z","caller":"traceutil/trace.go:171","msg":"trace[2062407114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"461.262649ms","start":"2026-03-24T14:49:17.413808Z","end":"2026-03-24T14:49:17.87507Z","steps":["trace[2062407114] 'agreement among raft nodes before linearized reading' (duration: 461.109238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:17.87512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.413778Z","time spent":"461.334611ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:49:17.875086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.52123ms","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-03-24T14:49:17.875185Z","caller":"traceutil/trace.go:171","msg":"trace[675583431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"330.646532ms","start":"2026-03-24T14:49:17.544517Z","end":"2026-03-24T14:49:17.875164Z","steps":["trace[675583431] 'agreement among raft nodes before linearized reading' (duration: 330.414799ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:17.874697Z","caller":"traceutil/trace.go:171","msg":"trace[37230605] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"725.328361ms","start":"2026-03-24T14:49:17.149321Z","end":"2026-03-24T14:49:17.874649Z","steps":["trace[37230605] 'process raft request' (duration: 725.066289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:17.875241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.544508Z","time spent":"330.705193ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:49:17.875362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.149265Z","time spent":"726.022799ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:18.342899Z","caller":"traceutil/trace.go:171","msg":"trace[1389459549] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"464.348441ms","start":"2026-03-24T14:49:17.878518Z","end":"2026-03-24T14:49:18.342867Z","steps":["trace[1389459549] 'process raft request' (duration: 449.664058ms)","trace[1389459549] 'compare' (duration: 14.36552ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:49:18.343081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.878493Z","time spent":"464.495225ms","remote":"127.0.0.1:39422","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-03-24T14:49:18.374455Z","caller":"traceutil/trace.go:171","msg":"trace[295379938] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"494.986014ms","start":"2026-03-24T14:49:17.879434Z","end":"2026-03-24T14:49:18.37442Z","steps":["trace[295379938] 'process raft request' (duration: 494.901163ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:18.374575Z","caller":"traceutil/trace.go:171","msg":"trace[1065882537] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:591; }","duration":"209.093451ms","start":"2026-03-24T14:49:18.165469Z","end":"2026-03-24T14:49:18.374563Z","steps":["trace[1065882537] 'read index received' (duration: 162.818347ms)","trace[1065882537] 'applied index is now lower than readState.Index' (duration: 46.273844ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:49:18.374637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.879415Z","time spent":"495.135505ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:18.374672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.199573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:49:18.374718Z","caller":"traceutil/trace.go:171","msg":"trace[1404441778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"209.310913ms","start":"2026-03-24T14:49:18.165396Z","end":"2026-03-24T14:49:18.374707Z","steps":["trace[1404441778] 'agreement among raft nodes before linearized reading' (duration: 209.223223ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:18.374466Z","caller":"traceutil/trace.go:171","msg":"trace[64947016] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"495.047635ms","start":"2026-03-24T14:49:17.87937Z","end":"2026-03-24T14:49:18.374418Z","steps":["trace[64947016] 'process raft request' (duration: 494.813212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:18.374826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.879352Z","time spent":"495.444748ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:18.375086Z","caller":"traceutil/trace.go:171","msg":"trace[1610473901] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"494.169777ms","start":"2026-03-24T14:49:17.880663Z","end":"2026-03-24T14:49:18.374833Z","steps":["trace[1610473901] 'process raft request' (duration: 493.709422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:18.375361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:17.880653Z","time spent":"494.55222ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:20.299329Z","caller":"traceutil/trace.go:171","msg":"trace[1647872390] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"136.138187ms","start":"2026-03-24T14:49:20.163165Z","end":"2026-03-24T14:49:20.299303Z","steps":["trace[1647872390] 'read index received' (duration: 135.968015ms)","trace[1647872390] 'applied index is now lower than readState.Index' (duration: 169.112µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:20.299378Z","caller":"traceutil/trace.go:171","msg":"trace[2085613098] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"180.941886ms","start":"2026-03-24T14:49:20.118402Z","end":"2026-03-24T14:49:20.299344Z","steps":["trace[2085613098] 'process raft request' (duration: 180.753534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:20.29945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.270418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:49:20.299483Z","caller":"traceutil/trace.go:171","msg":"trace[791804906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"136.351208ms","start":"2026-03-24T14:49:20.163122Z","end":"2026-03-24T14:49:20.299473Z","steps":["trace[791804906] 'agreement among raft nodes before linearized reading' (duration: 136.282918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:25.48066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.78686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-24T14:49:25.4808Z","caller":"traceutil/trace.go:171","msg":"trace[168631686] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:645; }","duration":"139.979854ms","start":"2026-03-24T14:49:25.340796Z","end":"2026-03-24T14:49:25.480776Z","steps":["trace[168631686] 'range keys from in-memory index tree' (duration: 139.582588ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:21.187978Z","caller":"traceutil/trace.go:171","msg":"trace[991940051] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"110.087726ms","start":"2026-03-24T14:50:21.07786Z","end":"2026-03-24T14:50:21.187948Z","steps":["trace[991940051] 'process raft request' (duration: 109.899761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:22.929591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.244794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:50:22.929722Z","caller":"traceutil/trace.go:171","msg":"trace[1430737481] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"130.70122ms","start":"2026-03-24T14:50:22.799005Z","end":"2026-03-24T14:50:22.929706Z","steps":["trace[1430737481] 'read index received' (duration: 48.641µs)","trace[1430737481] 'applied index is now lower than readState.Index' (duration: 130.651489ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:22.929804Z","caller":"traceutil/trace.go:171","msg":"trace[1482438709] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"153.592731ms","start":"2026-03-24T14:50:22.776193Z","end":"2026-03-24T14:50:22.929786Z","steps":["trace[1482438709] 'process raft request' (duration: 19.065399ms)","trace[1482438709] 'compare' (duration: 134.126591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:50:22.929868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.868075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-24T14:50:22.929898Z","caller":"traceutil/trace.go:171","msg":"trace[998855148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"130.897115ms","start":"2026-03-24T14:50:22.798991Z","end":"2026-03-24T14:50:22.929888Z","steps":["trace[998855148] 'agreement among raft nodes before linearized reading' (duration: 130.777462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:52.424289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.840741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:50:52.424389Z","caller":"traceutil/trace.go:171","msg":"trace[669393767] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1028; }","duration":"158.004536ms","start":"2026-03-24T14:50:52.266365Z","end":"2026-03-24T14:50:52.42437Z","steps":["trace[669393767] 'count revisions from in-memory index tree' (duration: 157.765869ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:52.928151Z","caller":"traceutil/trace.go:171","msg":"trace[442370046] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"149.842013ms","start":"2026-03-24T14:50:52.778282Z","end":"2026-03-24T14:50:52.928124Z","steps":["trace[442370046] 'process raft request' (duration: 149.653237ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:53.229821Z","caller":"traceutil/trace.go:171","msg":"trace[1568133865] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"113.555316ms","start":"2026-03-24T14:50:53.116225Z","end":"2026-03-24T14:50:53.229781Z","steps":["trace[1568133865] 'process raft request' (duration: 18.617951ms)","trace[1568133865] 'compare' (duration: 94.679046ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:53.707742Z","caller":"traceutil/trace.go:171","msg":"trace[176099330] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"194.641135ms","start":"2026-03-24T14:50:53.513074Z","end":"2026-03-24T14:50:53.707715Z","steps":["trace[176099330] 'process raft request' (duration: 194.48867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:53.913729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.160554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-24T14:50:53.913824Z","caller":"traceutil/trace.go:171","msg":"trace[307088591] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1037; }","duration":"174.597297ms","start":"2026-03-24T14:50:53.739211Z","end":"2026-03-24T14:50:53.913808Z","steps":["trace[307088591] 'process raft request' (duration: 48.175856ms)","trace[307088591] 'compare' (duration: 126.07196ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:55.150997Z","caller":"traceutil/trace.go:171","msg":"trace[1711408403] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"129.113629ms","start":"2026-03-24T14:50:55.02185Z","end":"2026-03-24T14:50:55.150963Z","steps":["trace[1711408403] 'process raft request' (duration: 128.424987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:55.426645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.579307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:50:55.426774Z","caller":"traceutil/trace.go:171","msg":"trace[204447272] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"188.576237ms","start":"2026-03-24T14:50:55.238175Z","end":"2026-03-24T14:50:55.426751Z","steps":["trace[204447272] 'process raft request' (duration: 30.785473ms)","trace[204447272] 'compare' (duration: 157.460264ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:57.127258Z","caller":"traceutil/trace.go:171","msg":"trace[1795306619] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"121.627402ms","start":"2026-03-24T14:50:57.005594Z","end":"2026-03-24T14:50:57.127222Z","steps":["trace[1795306619] 'process raft request' (duration: 85.398801ms)","trace[1795306619] 'compare' (duration: 35.958433ms)"],"step_count":2}