{"level":"warn","ts":"2026-05-11T05:37:11.803197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:37:11.803297Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-05-11T05:37:11.803374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:37:11.803384Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-11T05:37:11.803406Z","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-05-11T05:37:11.804116Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-11T05:37:11.804198Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-05-11T05:37:11.813199Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.790675ms"} {"level":"info","ts":"2026-05-11T05:37:11.823999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-11T05:37:11.824102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:37:11.824127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-11T05:37:11.824137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:37:11.824153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-11T05:37:11.824203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-11T05:37:11.834403Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:37:11.837092Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:37:11.838868Z","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-05-11T05:37:11.841498Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:37:11.841623Z","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-05-11T05:37:11.841705Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:37:11.841747Z","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-05-11T05:37:11.841896Z","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-05-11T05:37:11.84798Z","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-05-11T05:37:11.848153Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-11T05:37:11.848174Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-11T05:37:11.848186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:37:11.848223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:37:11.851312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-11T05:37:11.851497Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-11T05:37:12.324822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:37:12.324873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:37:12.324896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-11T05:37:12.324916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:37:12.324922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-11T05:37:12.32493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-11T05:37:12.324938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-11T05:37:12.336495Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:37:12.336846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:37:12.337017Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:37:12.337086Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:37:12.337063Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:37:12.336904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:37:12.338263Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:37:12.338358Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:37:12.338384Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:37:12.339449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:37:12.340334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-11T05:37:42.853624Z","caller":"traceutil/trace.go:171","msg":"trace[1747473692] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"195.760799ms","start":"2026-05-11T05:37:42.657836Z","end":"2026-05-11T05:37:42.853597Z","steps":["trace[1747473692] 'process raft request' (duration: 194.729024ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:43.104977Z","caller":"traceutil/trace.go:171","msg":"trace[186585149] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"193.370107ms","start":"2026-05-11T05:37:42.911573Z","end":"2026-05-11T05:37:43.104943Z","steps":["trace[186585149] 'process raft request' (duration: 190.504524ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:45.189166Z","caller":"traceutil/trace.go:171","msg":"trace[552510272] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"119.899025ms","start":"2026-05-11T05:37:45.069239Z","end":"2026-05-11T05:37:45.189138Z","steps":["trace[552510272] 'process raft request' (duration: 118.229053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:45.43015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.268372ms","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-05-11T05:37:45.430277Z","caller":"traceutil/trace.go:171","msg":"trace[1622288273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"222.43251ms","start":"2026-05-11T05:37:45.20782Z","end":"2026-05-11T05:37:45.430252Z","steps":["trace[1622288273] 'range keys from in-memory index tree' (duration: 222.156548ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:45.430426Z","caller":"traceutil/trace.go:171","msg":"trace[645364726] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"172.216293ms","start":"2026-05-11T05:37:45.258174Z","end":"2026-05-11T05:37:45.43039Z","steps":["trace[645364726] 'process raft request' (duration: 158.286348ms)","trace[645364726] 'compare' (duration: 13.296567ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:37:47.97466Z","caller":"traceutil/trace.go:171","msg":"trace[1024715060] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"100.404602ms","start":"2026-05-11T05:37:47.874233Z","end":"2026-05-11T05:37:47.974638Z","steps":["trace[1024715060] 'compare' (duration: 94.565002ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:50.95093Z","caller":"traceutil/trace.go:171","msg":"trace[91689754] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"114.741835ms","start":"2026-05-11T05:37:50.836165Z","end":"2026-05-11T05:37:50.950907Z","steps":["trace[91689754] 'read index received' (duration: 24.240556ms)","trace[91689754] 'applied index is now lower than readState.Index' (duration: 90.500238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:37:50.951122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.933664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-11T05:37:50.95116Z","caller":"traceutil/trace.go:171","msg":"trace[35146093] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"114.997736ms","start":"2026-05-11T05:37:50.836153Z","end":"2026-05-11T05:37:50.951151Z","steps":["trace[35146093] 'agreement among raft nodes before linearized reading' (duration: 114.841589ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:50.951263Z","caller":"traceutil/trace.go:171","msg":"trace[1368379010] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"123.162925ms","start":"2026-05-11T05:37:50.828074Z","end":"2026-05-11T05:37:50.951237Z","steps":["trace[1368379010] 'process raft request' (duration: 71.471205ms)","trace[1368379010] 'compare' (duration: 51.196379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:38:02.816969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.487446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:38:02.817207Z","caller":"traceutil/trace.go:171","msg":"trace[1883335088] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"508.796162ms","start":"2026-05-11T05:38:02.3084Z","end":"2026-05-11T05:38:02.817196Z","steps":["trace[1883335088] 'compare' (duration: 500.370491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:38:02.817259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:38:02.308379Z","time spent":"508.863194ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:41:35.014612Z","caller":"traceutil/trace.go:171","msg":"trace[1098841309] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"126.583321ms","start":"2026-05-11T05:41:34.887924Z","end":"2026-05-11T05:41:35.014507Z","steps":["trace[1098841309] 'process raft request' (duration: 41.661483ms)","trace[1098841309] 'compare' (duration: 84.795365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:35.370028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.087737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:35.370113Z","caller":"traceutil/trace.go:171","msg":"trace[1598205127] linearizableReadLoop","detail":"{readStateIndex:1644; appliedIndex:1643; }","duration":"201.459205ms","start":"2026-05-11T05:41:35.16864Z","end":"2026-05-11T05:41:35.370099Z","steps":["trace[1598205127] 'read index received' (duration: 22.269495ms)","trace[1598205127] 'applied index is now lower than readState.Index' (duration: 179.18881ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:35.370181Z","caller":"traceutil/trace.go:171","msg":"trace[202910896] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"300.697827ms","start":"2026-05-11T05:41:35.069468Z","end":"2026-05-11T05:41:35.370166Z","steps":["trace[202910896] 'process raft request' (duration: 121.397604ms)","trace[202910896] 'compare' (duration: 178.939654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:35.370212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.551846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:35.370246Z","caller":"traceutil/trace.go:171","msg":"trace[1454767253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1578; }","duration":"201.65835ms","start":"2026-05-11T05:41:35.168576Z","end":"2026-05-11T05:41:35.370235Z","steps":["trace[1454767253] 'agreement among raft nodes before linearized reading' (duration: 201.559727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.370247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.069454Z","time spent":"300.75854ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:35.714168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.306081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:35.714267Z","caller":"traceutil/trace.go:171","msg":"trace[435189499] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1578; }","duration":"124.516205ms","start":"2026-05-11T05:41:35.589731Z","end":"2026-05-11T05:41:35.714247Z","steps":["trace[435189499] 'count revisions from in-memory index tree' (duration: 124.185018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.714382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.199931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:41:35.714459Z","caller":"traceutil/trace.go:171","msg":"trace[1237462216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1578; }","duration":"192.436168ms","start":"2026-05-11T05:41:35.522005Z","end":"2026-05-11T05:41:35.714441Z","steps":["trace[1237462216] 'range keys from in-memory index tree' (duration: 192.039506ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:59.337037Z","caller":"traceutil/trace.go:171","msg":"trace[1277996185] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"334.402335ms","start":"2026-05-11T05:41:59.002603Z","end":"2026-05-11T05:41:59.337006Z","steps":["trace[1277996185] 'process raft request' (duration: 334.255911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:59.337239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:59.002581Z","time spent":"334.56454ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:41:59.607345Z","caller":"traceutil/trace.go:171","msg":"trace[218639318] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"494.279691ms","start":"2026-05-11T05:41:59.113039Z","end":"2026-05-11T05:41:59.607319Z","steps":["trace[218639318] 'process raft request' (duration: 428.451657ms)","trace[218639318] 'compare' (duration: 65.550946ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:59.607437Z","caller":"traceutil/trace.go:171","msg":"trace[1001360337] linearizableReadLoop","detail":"{readStateIndex:1727; appliedIndex:1725; }","duration":"439.6221ms","start":"2026-05-11T05:41:59.16779Z","end":"2026-05-11T05:41:59.607413Z","steps":["trace[1001360337] 'read index received' (duration: 169.124156ms)","trace[1001360337] 'applied index is now lower than readState.Index' (duration: 270.496483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:59.607499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:59.113012Z","time spent":"494.407125ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:59.607806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.01644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:59.608005Z","caller":"traceutil/trace.go:171","msg":"trace[1944855013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1657; }","duration":"440.164453ms","start":"2026-05-11T05:41:59.167737Z","end":"2026-05-11T05:41:59.607902Z","steps":["trace[1944855013] 'agreement among raft nodes before linearized reading' (duration: 440.0192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:59.608106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:59.167717Z","time spent":"440.335098ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:41:59.607825Z","caller":"traceutil/trace.go:171","msg":"trace[865346121] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"296.142774ms","start":"2026-05-11T05:41:59.311376Z","end":"2026-05-11T05:41:59.607519Z","steps":["trace[865346121] 'process raft request' (duration: 295.895638ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:00.535946Z","caller":"traceutil/trace.go:171","msg":"trace[1814722636] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"291.88432ms","start":"2026-05-11T05:42:00.244036Z","end":"2026-05-11T05:42:00.53592Z","steps":["trace[1814722636] 'process raft request' (duration: 291.741926ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:00.578279Z","caller":"traceutil/trace.go:171","msg":"trace[1511019551] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"332.905685ms","start":"2026-05-11T05:42:00.245345Z","end":"2026-05-11T05:42:00.578251Z","steps":["trace[1511019551] 'process raft request' (duration: 332.744571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.578456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:00.245329Z","time spent":"333.033358ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:01.508219Z","caller":"traceutil/trace.go:171","msg":"trace[1165549905] linearizableReadLoop","detail":"{readStateIndex:1733; appliedIndex:1732; }","duration":"166.777761ms","start":"2026-05-11T05:42:01.341338Z","end":"2026-05-11T05:42:01.508115Z","steps":["trace[1165549905] 'read index received' (duration: 116.313293ms)","trace[1165549905] 'applied index is now lower than readState.Index' (duration: 50.462908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:01.508425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.071119ms","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-05-11T05:42:01.508487Z","caller":"traceutil/trace.go:171","msg":"trace[1404129435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1661; }","duration":"167.206864ms","start":"2026-05-11T05:42:01.341263Z","end":"2026-05-11T05:42:01.50847Z","steps":["trace[1404129435] 'agreement among raft nodes before linearized reading' (duration: 167.015128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:30.087083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.105875ms","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-05-11T05:42:30.087201Z","caller":"traceutil/trace.go:171","msg":"trace[938201011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1807; }","duration":"263.238328ms","start":"2026-05-11T05:42:29.823941Z","end":"2026-05-11T05:42:30.08718Z","steps":["trace[938201011] 'range keys from in-memory index tree' (duration: 262.93111ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:30.26858Z","caller":"traceutil/trace.go:171","msg":"trace[588595943] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1886; }","duration":"101.182415ms","start":"2026-05-11T05:42:30.167376Z","end":"2026-05-11T05:42:30.268558Z","steps":["trace[588595943] 'read index received' (duration: 15.59302ms)","trace[588595943] 'applied index is now lower than readState.Index' (duration: 85.588485ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:30.268709Z","caller":"traceutil/trace.go:171","msg":"trace[810643160] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"177.007507ms","start":"2026-05-11T05:42:30.091689Z","end":"2026-05-11T05:42:30.268697Z","steps":["trace[810643160] 'process raft request' (duration: 141.546303ms)","trace[810643160] 'compare' (duration: 35.052323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:30.269055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.649677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:30.26916Z","caller":"traceutil/trace.go:171","msg":"trace[148827386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1810; }","duration":"101.76614ms","start":"2026-05-11T05:42:30.167371Z","end":"2026-05-11T05:42:30.269137Z","steps":["trace[148827386] 'agreement among raft nodes before linearized reading' (duration: 101.605365ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:35.017371Z","caller":"traceutil/trace.go:171","msg":"trace[170529681] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"185.463034ms","start":"2026-05-11T05:42:34.831878Z","end":"2026-05-11T05:42:35.017341Z","steps":["trace[170529681] 'read index received' (duration: 185.318771ms)","trace[170529681] 'applied index is now lower than readState.Index' (duration: 143.253µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:35.017507Z","caller":"traceutil/trace.go:171","msg":"trace[1580718777] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"354.502637ms","start":"2026-05-11T05:42:34.662982Z","end":"2026-05-11T05:42:35.017485Z","steps":["trace[1580718777] 'process raft request' (duration: 354.189988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.017618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:34.662958Z","time spent":"354.582208ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:35.017783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.884246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T05:42:35.017888Z","caller":"traceutil/trace.go:171","msg":"trace[557803689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1857; }","duration":"186.026129ms","start":"2026-05-11T05:42:34.831829Z","end":"2026-05-11T05:42:35.017855Z","steps":["trace[557803689] 'agreement among raft nodes before linearized reading' (duration: 185.758543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.018219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.864574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T05:42:35.018481Z","caller":"traceutil/trace.go:171","msg":"trace[1095420604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"183.137383ms","start":"2026-05-11T05:42:34.835321Z","end":"2026-05-11T05:42:35.018458Z","steps":["trace[1095420604] 'agreement among raft nodes before linearized reading' (duration: 182.251159ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:35.402858Z","caller":"traceutil/trace.go:171","msg":"trace[562251575] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"380.683942ms","start":"2026-05-11T05:42:35.022149Z","end":"2026-05-11T05:42:35.402833Z","steps":["trace[562251575] 'process raft request' (duration: 380.530467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.402995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:35.022129Z","time spent":"380.798765ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:35.474788Z","caller":"traceutil/trace.go:171","msg":"trace[1993421642] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1937; }","duration":"307.039219ms","start":"2026-05-11T05:42:35.167723Z","end":"2026-05-11T05:42:35.474762Z","steps":["trace[1993421642] 'read index received' (duration: 235.021369ms)","trace[1993421642] 'applied index is now lower than readState.Index' (duration: 72.01686ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:35.47492Z","caller":"traceutil/trace.go:171","msg":"trace[910437615] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"452.25132ms","start":"2026-05-11T05:42:35.022613Z","end":"2026-05-11T05:42:35.474864Z","steps":["trace[910437615] 'process raft request' (duration: 451.987822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.475016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.275755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:35.475074Z","caller":"traceutil/trace.go:171","msg":"trace[2087221347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"307.376198ms","start":"2026-05-11T05:42:35.167683Z","end":"2026-05-11T05:42:35.475059Z","steps":["trace[2087221347] 'agreement among raft nodes before linearized reading' (duration: 307.273295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.475119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:35.167668Z","time spent":"307.442ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T05:42:35.475125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:35.022596Z","time spent":"452.422253ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:36.416457Z","caller":"traceutil/trace.go:171","msg":"trace[993237159] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"207.116968ms","start":"2026-05-11T05:42:36.20931Z","end":"2026-05-11T05:42:36.416427Z","steps":["trace[993237159] 'process raft request' (duration: 206.869431ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:36.823315Z","caller":"traceutil/trace.go:171","msg":"trace[1617070296] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1943; }","duration":"555.742076ms","start":"2026-05-11T05:42:36.267548Z","end":"2026-05-11T05:42:36.82329Z","steps":["trace[1617070296] 'read index received' (duration: 148.613612ms)","trace[1617070296] 'applied index is now lower than readState.Index' (duration: 407.127034ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:36.823461Z","caller":"traceutil/trace.go:171","msg":"trace[1873944606] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"570.75397ms","start":"2026-05-11T05:42:36.252695Z","end":"2026-05-11T05:42:36.823449Z","steps":["trace[1873944606] 'process raft request' (duration: 487.762375ms)","trace[1873944606] 'compare' (duration: 82.592944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:36.823542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:36.252664Z","time spent":"570.820472ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:42:36.823808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.26405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:42:36.823856Z","caller":"traceutil/trace.go:171","msg":"trace[151800546] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1864; }","duration":"556.370673ms","start":"2026-05-11T05:42:36.267476Z","end":"2026-05-11T05:42:36.823847Z","steps":["trace[151800546] 'agreement among raft nodes before linearized reading' (duration: 556.254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:36.823882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:36.267454Z","time spent":"556.420605ms","remote":"127.0.0.1:48842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:42:36.823982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.567756ms","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":"warn","ts":"2026-05-11T05:42:36.82423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.38303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:36.824241Z","caller":"traceutil/trace.go:171","msg":"trace[1756008178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"534.845693ms","start":"2026-05-11T05:42:36.289372Z","end":"2026-05-11T05:42:36.824218Z","steps":["trace[1756008178] 'agreement among raft nodes before linearized reading' (duration: 534.456363ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:36.824303Z","caller":"traceutil/trace.go:171","msg":"trace[45009724] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1864; }","duration":"241.7237ms","start":"2026-05-11T05:42:36.582559Z","end":"2026-05-11T05:42:36.824283Z","steps":["trace[45009724] 'agreement among raft nodes before linearized reading' (duration: 241.347199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:36.824338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:36.28935Z","time spent":"534.971727ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T05:42:37.417928Z","caller":"traceutil/trace.go:171","msg":"trace[1691809204] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"162.021404ms","start":"2026-05-11T05:42:37.255882Z","end":"2026-05-11T05:42:37.417904Z","steps":["trace[1691809204] 'process raft request' (duration: 161.829818ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:37.670155Z","caller":"traceutil/trace.go:171","msg":"trace[663863193] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"247.054323ms","start":"2026-05-11T05:42:37.423064Z","end":"2026-05-11T05:42:37.670118Z","steps":["trace[663863193] 'process raft request' (duration: 202.985906ms)","trace[663863193] 'compare' (duration: 43.751158ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:37.734353Z","caller":"traceutil/trace.go:171","msg":"trace[72406442] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1948; }","duration":"255.301536ms","start":"2026-05-11T05:42:37.479025Z","end":"2026-05-11T05:42:37.734326Z","steps":["trace[72406442] 'read index received' (duration: 147.009289ms)","trace[72406442] 'applied index is now lower than readState.Index' (duration: 108.291216ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:37.734472Z","caller":"traceutil/trace.go:171","msg":"trace[1978108467] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"310.941153ms","start":"2026-05-11T05:42:37.42352Z","end":"2026-05-11T05:42:37.734461Z","steps":["trace[1978108467] 'process raft request' (duration: 310.671276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:37.734573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:37.423514Z","time spent":"310.985666ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:37.734764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.725447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T05:42:37.734842Z","caller":"traceutil/trace.go:171","msg":"trace[1462781550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1869; }","duration":"255.8332ms","start":"2026-05-11T05:42:37.478991Z","end":"2026-05-11T05:42:37.734824Z","steps":["trace[1462781550] 'agreement among raft nodes before linearized reading' (duration: 255.642885ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:37.932043Z","caller":"traceutil/trace.go:171","msg":"trace[675476979] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"179.995148ms","start":"2026-05-11T05:42:37.752011Z","end":"2026-05-11T05:42:37.932006Z","steps":["trace[675476979] 'process raft request' (duration: 169.780142ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:37.955294Z","caller":"traceutil/trace.go:171","msg":"trace[222371602] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"200.221103ms","start":"2026-05-11T05:42:37.755046Z","end":"2026-05-11T05:42:37.955267Z","steps":["trace[222371602] 'process raft request' (duration: 199.676817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:51.55827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.840798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:42:51.558617Z","caller":"traceutil/trace.go:171","msg":"trace[1541338344] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"552.770746ms","start":"2026-05-11T05:42:51.005818Z","end":"2026-05-11T05:42:51.558589Z","steps":["trace[1541338344] 'read index received' (duration: 123.510216ms)","trace[1541338344] 'applied index is now lower than readState.Index' (duration: 429.25756ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:51.558958Z","caller":"traceutil/trace.go:171","msg":"trace[747976043] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"615.003341ms","start":"2026-05-11T05:42:50.943917Z","end":"2026-05-11T05:42:51.558921Z","steps":["trace[747976043] 'process raft request' (duration: 185.409312ms)","trace[747976043] 'compare' (duration: 428.627473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:51.559097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:50.9439Z","time spent":"615.110473ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:51.588693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.867077ms","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-05-11T05:42:51.588773Z","caller":"traceutil/trace.go:171","msg":"trace[759684164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1980; }","duration":"582.981329ms","start":"2026-05-11T05:42:51.005769Z","end":"2026-05-11T05:42:51.58875Z","steps":["trace[759684164] 'agreement among raft nodes before linearized reading' (duration: 552.91804ms)","trace[759684164] 'range keys from in-memory index tree' (duration: 29.880315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:51.588776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.889371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:42:51.588819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:51.00575Z","time spent":"583.059131ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T05:42:51.588817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.785858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:42:51.588807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.184045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:42:51.58886Z","caller":"traceutil/trace.go:171","msg":"trace[908316531] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1980; }","duration":"474.039986ms","start":"2026-05-11T05:42:51.114799Z","end":"2026-05-11T05:42:51.588839Z","steps":["trace[908316531] 'agreement among raft nodes before linearized reading' (duration: 473.886762ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:51.588893Z","caller":"traceutil/trace.go:171","msg":"trace[1297723546] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1980; }","duration":"441.90671ms","start":"2026-05-11T05:42:51.146971Z","end":"2026-05-11T05:42:51.588877Z","steps":["trace[1297723546] 'agreement among raft nodes before linearized reading' (duration: 441.770727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:51.588919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:51.114779Z","time spent":"474.128578ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:42:51.588916Z","caller":"traceutil/trace.go:171","msg":"trace[1235299393] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1980; }","duration":"332.31584ms","start":"2026-05-11T05:42:51.256582Z","end":"2026-05-11T05:42:51.588898Z","steps":["trace[1235299393] 'agreement among raft nodes before linearized reading' (duration: 332.126724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:51.588938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:51.146954Z","time spent":"441.976322ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-11T05:42:51.58898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:51.256525Z","time spent":"332.443443ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:42:51.970925Z","caller":"traceutil/trace.go:171","msg":"trace[738374578] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"111.606607ms","start":"2026-05-11T05:42:51.859281Z","end":"2026-05-11T05:42:51.970888Z","steps":["trace[738374578] 'process raft request' (duration: 99.618543ms)","trace[738374578] 'compare' (duration: 11.861059ms)"],"step_count":2}