{"level":"warn","ts":"2026-08-22T18:39:52.543348Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T18:39:52.543422Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-08-22T18:39:52.543482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T18:39:52.543492Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-22T18:39:52.543512Z","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-08-22T18:39:52.544094Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-08-22T18:39:52.544176Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-08-22T18:39:52.549347Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.950846ms"} {"level":"info","ts":"2026-08-22T18:39:52.563426Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-08-22T18:39:52.563484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T18:39:52.563498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-08-22T18:39:52.563508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T18:39:52.563516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-08-22T18:39:52.563545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-08-22T18:39:52.583329Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T18:39:52.593255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T18:39:52.594402Z","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-08-22T18:39:52.596351Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T18:39:52.596554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T18:39:52.597389Z","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-08-22T18:39:52.597588Z","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-08-22T18:39:52.597643Z","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-08-22T18:39:52.597695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-08-22T18:39:52.598034Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-22T18:39:52.600944Z","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-08-22T18:39:52.601148Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T18:39:52.601177Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T18:39:52.601238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-22T18:39:52.601249Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-22T18:39:53.565059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T18:39:53.565142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T18:39:53.565181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-08-22T18:39:53.565202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-08-22T18:39:53.565216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-22T18:39:53.565232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-08-22T18:39:53.565245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-22T18:39:53.566039Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:39:53.566735Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T18:39:53.566923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:39:53.56695Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:39:53.567102Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:39:53.567221Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:39:53.567269Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:39:53.567396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T18:39:53.567481Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T18:39:53.570762Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-08-22T18:39:53.570873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T18:40:04.355011Z","caller":"traceutil/trace.go:171","msg":"trace[451839328] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"178.068647ms","start":"2026-08-22T18:40:04.17691Z","end":"2026-08-22T18:40:04.354979Z","steps":["trace[451839328] 'process raft request' (duration: 138.662268ms)","trace[451839328] 'compare' (duration: 39.207857ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:04.355357Z","caller":"traceutil/trace.go:171","msg":"trace[1408890022] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"177.643241ms","start":"2026-08-22T18:40:04.177706Z","end":"2026-08-22T18:40:04.355349Z","steps":["trace[1408890022] 'process raft request' (duration: 177.209365ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:04.355493Z","caller":"traceutil/trace.go:171","msg":"trace[1615778206] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"176.83728ms","start":"2026-08-22T18:40:04.178633Z","end":"2026-08-22T18:40:04.35547Z","steps":["trace[1615778206] 'process raft request' (duration: 176.324763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:04.355495Z","caller":"traceutil/trace.go:171","msg":"trace[13696435] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"170.108099ms","start":"2026-08-22T18:40:04.185251Z","end":"2026-08-22T18:40:04.355359Z","steps":["trace[13696435] 'process raft request' (duration: 169.773184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:04.914574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.376886ms","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-08-22T18:40:04.914685Z","caller":"traceutil/trace.go:171","msg":"trace[1307878524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"125.554959ms","start":"2026-08-22T18:40:04.789111Z","end":"2026-08-22T18:40:04.914666Z","steps":["trace[1307878524] 'range keys from in-memory index tree' (duration: 125.275825ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:05.269738Z","caller":"traceutil/trace.go:171","msg":"trace[1926139] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"129.956824ms","start":"2026-08-22T18:40:05.139756Z","end":"2026-08-22T18:40:05.269712Z","steps":["trace[1926139] 'process raft request' (duration: 129.869043ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:05.269889Z","caller":"traceutil/trace.go:171","msg":"trace[534325252] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"249.603439ms","start":"2026-08-22T18:40:05.020244Z","end":"2026-08-22T18:40:05.269847Z","steps":["trace[534325252] 'process raft request' (duration: 168.371086ms)","trace[534325252] 'compare' (duration: 80.645865ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:05.270115Z","caller":"traceutil/trace.go:171","msg":"trace[564416675] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"155.37877ms","start":"2026-08-22T18:40:05.114718Z","end":"2026-08-22T18:40:05.270097Z","steps":["trace[564416675] 'read index received' (duration: 73.875373ms)","trace[564416675] 'applied index is now lower than readState.Index' (duration: 81.499057ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:05.270344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.614223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T18:40:05.270393Z","caller":"traceutil/trace.go:171","msg":"trace[1219663502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"155.707924ms","start":"2026-08-22T18:40:05.114673Z","end":"2026-08-22T18:40:05.27038Z","steps":["trace[1219663502] 'agreement among raft nodes before linearized reading' (duration: 155.523221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:05.617592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.765038ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-22T18:40:05.617761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:05.314788Z","time spent":"302.966643ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T18:40:05.640967Z","caller":"traceutil/trace.go:171","msg":"trace[491319115] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"276.666616ms","start":"2026-08-22T18:40:05.364263Z","end":"2026-08-22T18:40:05.640929Z","steps":["trace[491319115] 'process raft request' (duration: 276.360042ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:05.696533Z","caller":"traceutil/trace.go:171","msg":"trace[1442490915] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"227.066742ms","start":"2026-08-22T18:40:05.469432Z","end":"2026-08-22T18:40:05.696499Z","steps":["trace[1442490915] 'process raft request' (duration: 226.85201ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:26.563807Z","caller":"traceutil/trace.go:171","msg":"trace[1144200892] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"118.828632ms","start":"2026-08-22T18:40:26.444951Z","end":"2026-08-22T18:40:26.56378Z","steps":["trace[1144200892] 'process raft request' (duration: 118.63311ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:29.790296Z","caller":"traceutil/trace.go:171","msg":"trace[908089788] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"312.269578ms","start":"2026-08-22T18:40:29.478Z","end":"2026-08-22T18:40:29.79027Z","steps":["trace[908089788] 'process raft request' (duration: 312.119676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:29.79115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:29.477983Z","time spent":"312.396229ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:29.791718Z","caller":"traceutil/trace.go:171","msg":"trace[1702491988] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"265.288418ms","start":"2026-08-22T18:40:29.526402Z","end":"2026-08-22T18:40:29.79169Z","steps":["trace[1702491988] 'read index received' (duration: 263.710069ms)","trace[1702491988] 'applied index is now lower than readState.Index' (duration: 1.577209ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:29.7918Z","caller":"traceutil/trace.go:171","msg":"trace[1355622365] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"313.302661ms","start":"2026-08-22T18:40:29.478468Z","end":"2026-08-22T18:40:29.79177Z","steps":["trace[1355622365] 'process raft request' (duration: 313.096458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:29.79189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.47578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:29.792034Z","caller":"traceutil/trace.go:171","msg":"trace[667933347] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:522; }","duration":"265.639872ms","start":"2026-08-22T18:40:29.526374Z","end":"2026-08-22T18:40:29.792014Z","steps":["trace[667933347] 'agreement among raft nodes before linearized reading' (duration: 265.420969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:29.791958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:29.478459Z","time spent":"313.414692ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:30.103555Z","caller":"traceutil/trace.go:171","msg":"trace[627007679] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"118.826516ms","start":"2026-08-22T18:40:29.984703Z","end":"2026-08-22T18:40:30.103529Z","steps":["trace[627007679] 'process raft request' (duration: 118.634394ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:30.111976Z","caller":"traceutil/trace.go:171","msg":"trace[1730924062] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"125.497025ms","start":"2026-08-22T18:40:29.98646Z","end":"2026-08-22T18:40:30.111957Z","steps":["trace[1730924062] 'process raft request' (duration: 124.818737ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:30.112055Z","caller":"traceutil/trace.go:171","msg":"trace[1322210994] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"107.710447ms","start":"2026-08-22T18:40:30.004337Z","end":"2026-08-22T18:40:30.112048Z","steps":["trace[1322210994] 'process raft request' (duration: 107.043829ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:30.112144Z","caller":"traceutil/trace.go:171","msg":"trace[2040683367] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"118.820136ms","start":"2026-08-22T18:40:29.993317Z","end":"2026-08-22T18:40:30.112137Z","steps":["trace[2040683367] 'process raft request' (duration: 118.024267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:36.376937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.041373ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:36.377026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.69081ms","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-08-22T18:40:36.377115Z","caller":"traceutil/trace.go:171","msg":"trace[377197505] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"607.242564ms","start":"2026-08-22T18:40:35.769846Z","end":"2026-08-22T18:40:36.377089Z","steps":["trace[377197505] 'range keys from in-memory index tree' (duration: 607.027122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:36.377134Z","caller":"traceutil/trace.go:171","msg":"trace[951912704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"555.843699ms","start":"2026-08-22T18:40:35.821268Z","end":"2026-08-22T18:40:36.377112Z","steps":["trace[951912704] 'range keys from in-memory index tree' (duration: 555.51637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:36.377195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:35.821258Z","time spent":"555.919893ms","remote":"127.0.0.1:41826","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-08-22T18:40:36.377218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.649425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T18:40:36.377305Z","caller":"traceutil/trace.go:171","msg":"trace[267933088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"560.784723ms","start":"2026-08-22T18:40:35.816496Z","end":"2026-08-22T18:40:36.37728Z","steps":["trace[267933088] 'range keys from in-memory index tree' (duration: 560.508557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:36.377345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:35.81647Z","time spent":"560.865327ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T18:40:36.61758Z","caller":"traceutil/trace.go:171","msg":"trace[1314382364] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"263.912593ms","start":"2026-08-22T18:40:36.353583Z","end":"2026-08-22T18:40:36.617495Z","steps":["trace[1314382364] 'read index received' (duration: 258.101503ms)","trace[1314382364] 'applied index is now lower than readState.Index' (duration: 5.80979ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:36.617762Z","caller":"traceutil/trace.go:171","msg":"trace[1282037348] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"535.61457ms","start":"2026-08-22T18:40:36.082096Z","end":"2026-08-22T18:40:36.61771Z","steps":["trace[1282037348] 'process raft request' (duration: 530.365781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:36.617893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.284705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gdgr7\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-22T18:40:36.617939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.082072Z","time spent":"535.78749ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:36.618019Z","caller":"traceutil/trace.go:171","msg":"trace[270346161] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gdgr7; range_end:; response_count:1; response_revision:625; }","duration":"264.454465ms","start":"2026-08-22T18:40:36.353536Z","end":"2026-08-22T18:40:36.61799Z","steps":["trace[270346161] 'agreement among raft nodes before linearized reading' (duration: 264.174969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.086736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.696809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:40:37.08691Z","caller":"traceutil/trace.go:171","msg":"trace[1628833461] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"469.22446ms","start":"2026-08-22T18:40:36.617668Z","end":"2026-08-22T18:40:37.086892Z","steps":["trace[1628833461] 'read index received' (duration: 358.291248ms)","trace[1628833461] 'applied index is now lower than readState.Index' (duration: 110.932372ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:37.086986Z","caller":"traceutil/trace.go:171","msg":"trace[2006101017] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"705.003694ms","start":"2026-08-22T18:40:36.381974Z","end":"2026-08-22T18:40:37.086977Z","steps":["trace[2006101017] 'process raft request' (duration: 704.863816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.08705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.381965Z","time spent":"705.051677ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:40:37.087232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.302071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T18:40:37.087262Z","caller":"traceutil/trace.go:171","msg":"trace[272349856] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"705.666562ms","start":"2026-08-22T18:40:36.381379Z","end":"2026-08-22T18:40:37.087045Z","steps":["trace[272349856] 'process raft request' (duration: 594.56778ms)","trace[272349856] 'compare' (duration: 110.514299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:37.087525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.381361Z","time spent":"706.052254ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:37.087354Z","caller":"traceutil/trace.go:171","msg":"trace[1975286105] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:627; }","duration":"540.479001ms","start":"2026-08-22T18:40:36.546852Z","end":"2026-08-22T18:40:37.087331Z","steps":["trace[1975286105] 'agreement among raft nodes before linearized reading' (duration: 540.209536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.087775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.546829Z","time spent":"540.930966ms","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"info","ts":"2026-08-22T18:40:37.12084Z","caller":"traceutil/trace.go:171","msg":"trace[1007267071] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"489.929595ms","start":"2026-08-22T18:40:36.630859Z","end":"2026-08-22T18:40:37.120789Z","steps":["trace[1007267071] 'process raft request' (duration: 489.680771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.121128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.809735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:37.121257Z","caller":"traceutil/trace.go:171","msg":"trace[1186077415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"385.976016ms","start":"2026-08-22T18:40:36.735261Z","end":"2026-08-22T18:40:37.121237Z","steps":["trace[1186077415] 'agreement among raft nodes before linearized reading' (duration: 385.793765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.121334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.735243Z","time spent":"386.079101ms","remote":"127.0.0.1:41602","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-08-22T18:40:37.121359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.630841Z","time spent":"490.095635ms","remote":"127.0.0.1:41770","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-08-22T18:40:37.121652Z","caller":"traceutil/trace.go:171","msg":"trace[1755612884] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"442.651577ms","start":"2026-08-22T18:40:36.678976Z","end":"2026-08-22T18:40:37.121627Z","steps":["trace[1755612884] 'process raft request' (duration: 441.669311ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:37.121716Z","caller":"traceutil/trace.go:171","msg":"trace[779427849] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"331.17367ms","start":"2026-08-22T18:40:36.790488Z","end":"2026-08-22T18:40:37.121662Z","steps":["trace[779427849] 'process raft request' (duration: 330.380935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:37.12179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.678948Z","time spent":"442.784445ms","remote":"127.0.0.1:42076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:40:37.122433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:36.790459Z","time spent":"331.848458ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:40:37.73853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342814136109824375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T18:40:38.25885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.749152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:40:38.258987Z","caller":"traceutil/trace.go:171","msg":"trace[595982058] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.117660543s","start":"2026-08-22T18:40:37.141284Z","end":"2026-08-22T18:40:38.258944Z","steps":["trace[595982058] 'process raft request' (duration: 677.742001ms)","trace[595982058] 'compare' (duration: 439.452386ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:38.259081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:37.141261Z","time spent":"1.117776359s","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:38.325092Z","caller":"traceutil/trace.go:171","msg":"trace[1767891628] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.181433485s","start":"2026-08-22T18:40:37.143631Z","end":"2026-08-22T18:40:38.325065Z","steps":["trace[1767891628] 'process raft request' (duration: 1.180841092s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:38.325258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:37.143615Z","time spent":"1.181552812s","remote":"127.0.0.1:41826","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-08-22T18:40:38.326085Z","caller":"traceutil/trace.go:171","msg":"trace[982265291] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"1.088136821s","start":"2026-08-22T18:40:37.237927Z","end":"2026-08-22T18:40:38.326064Z","steps":["trace[982265291] 'read index received' (duration: 581.151742ms)","trace[982265291] 'applied index is now lower than readState.Index' (duration: 506.983798ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:38.326198Z","caller":"traceutil/trace.go:171","msg":"trace[1682830749] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"796.369662ms","start":"2026-08-22T18:40:37.529813Z","end":"2026-08-22T18:40:38.326182Z","steps":["trace[1682830749] 'process raft request' (duration: 794.994836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:38.326298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:37.529779Z","time spent":"796.464428ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:40:38.326676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088751515s","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-08-22T18:40:38.32672Z","caller":"traceutil/trace.go:171","msg":"trace[516035459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.08885427s","start":"2026-08-22T18:40:37.237855Z","end":"2026-08-22T18:40:38.326709Z","steps":["trace[516035459] 'agreement among raft nodes before linearized reading' (duration: 1.088715523s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:38.326752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:37.237832Z","time spent":"1.088912923s","remote":"127.0.0.1:41826","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-08-22T18:40:39.924605Z","caller":"traceutil/trace.go:171","msg":"trace[1965125707] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"362.94599ms","start":"2026-08-22T18:40:39.561629Z","end":"2026-08-22T18:40:39.924575Z","steps":["trace[1965125707] 'read index received' (duration: 343.079209ms)","trace[1965125707] 'applied index is now lower than readState.Index' (duration: 19.865451ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:39.924626Z","caller":"traceutil/trace.go:171","msg":"trace[1641121395] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"411.685657ms","start":"2026-08-22T18:40:39.512909Z","end":"2026-08-22T18:40:39.924594Z","steps":["trace[1641121395] 'process raft request' (duration: 391.961574ms)","trace[1641121395] 'compare' (duration: 19.278359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:39.924814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.673085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:39.92487Z","caller":"traceutil/trace.go:171","msg":"trace[1322941369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"190.789532ms","start":"2026-08-22T18:40:39.734067Z","end":"2026-08-22T18:40:39.924857Z","steps":["trace[1322941369] 'agreement among raft nodes before linearized reading' (duration: 190.697187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:39.924862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:39.512873Z","time spent":"411.820785ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:40:39.924814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.167262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-22T18:40:39.924988Z","caller":"traceutil/trace.go:171","msg":"trace[770036709] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:654; }","duration":"363.329531ms","start":"2026-08-22T18:40:39.561593Z","end":"2026-08-22T18:40:39.924923Z","steps":["trace[770036709] 'agreement among raft nodes before linearized reading' (duration: 363.12141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:39.925093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:39.561572Z","time spent":"363.507191ms","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-22T18:40:44.940866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.022427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:40:44.941083Z","caller":"traceutil/trace.go:171","msg":"trace[1355024714] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"480.003155ms","start":"2026-08-22T18:40:44.461061Z","end":"2026-08-22T18:40:44.941064Z","steps":["trace[1355024714] 'process raft request' (duration: 479.935301ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:44.941061Z","caller":"traceutil/trace.go:171","msg":"trace[202478023] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"510.540881ms","start":"2026-08-22T18:40:44.430477Z","end":"2026-08-22T18:40:44.941018Z","steps":["trace[202478023] 'process raft request' (duration: 132.272971ms)","trace[202478023] 'compare' (duration: 377.539122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:44.941154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:44.46103Z","time spent":"480.09787ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:40:44.941184Z","caller":"traceutil/trace.go:171","msg":"trace[372501842] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"507.723595ms","start":"2026-08-22T18:40:44.433408Z","end":"2026-08-22T18:40:44.941132Z","steps":["trace[372501842] 'read index received' (duration: 129.324268ms)","trace[372501842] 'applied index is now lower than readState.Index' (duration: 378.389647ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:44.941258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:44.430452Z","time spent":"510.70671ms","remote":"127.0.0.1:41770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:40:44.941353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.935626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-22T18:40:44.941414Z","caller":"traceutil/trace.go:171","msg":"trace[669978969] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:734; }","duration":"508.01655ms","start":"2026-08-22T18:40:44.43338Z","end":"2026-08-22T18:40:44.941397Z","steps":["trace[669978969] 'agreement among raft nodes before linearized reading' (duration: 507.902254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:44.941451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:44.433367Z","time spent":"508.076063ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-08-22T18:40:44.941468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.492933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-08-22T18:40:44.941514Z","caller":"traceutil/trace.go:171","msg":"trace[923619084] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:734; }","duration":"507.595358ms","start":"2026-08-22T18:40:44.433903Z","end":"2026-08-22T18:40:44.941498Z","steps":["trace[923619084] 'agreement among raft nodes before linearized reading' (duration: 507.464191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:44.941523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.724981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:44.941563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:44.433886Z","time spent":"507.667772ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":856,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-08-22T18:40:44.941573Z","caller":"traceutil/trace.go:171","msg":"trace[1333477057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"207.795654ms","start":"2026-08-22T18:40:44.73376Z","end":"2026-08-22T18:40:44.941555Z","steps":["trace[1333477057] 'agreement among raft nodes before linearized reading' (duration: 207.72292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:44.941668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.34504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-08-22T18:40:44.941718Z","caller":"traceutil/trace.go:171","msg":"trace[2102721869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:734; }","duration":"386.435125ms","start":"2026-08-22T18:40:44.555267Z","end":"2026-08-22T18:40:44.941702Z","steps":["trace[2102721869] 'agreement among raft nodes before linearized reading' (duration: 386.253255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:44.941766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:44.555249Z","time spent":"386.505959ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-22T18:40:45.171344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.38938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:45.171389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.793509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:45.171405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.611631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.171439Z","caller":"traceutil/trace.go:171","msg":"trace[1097974460] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:734; }","duration":"123.544157ms","start":"2026-08-22T18:40:45.047875Z","end":"2026-08-22T18:40:45.171419Z","steps":["trace[1097974460] 'count revisions from in-memory index tree' (duration: 123.239372ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.171473Z","caller":"traceutil/trace.go:171","msg":"trace[2009495530] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:734; }","duration":"122.917015ms","start":"2026-08-22T18:40:45.048534Z","end":"2026-08-22T18:40:45.171451Z","steps":["trace[2009495530] 'range keys from in-memory index tree' (duration: 122.724276ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.171498Z","caller":"traceutil/trace.go:171","msg":"trace[2019963130] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:734; }","duration":"123.731317ms","start":"2026-08-22T18:40:45.047742Z","end":"2026-08-22T18:40:45.171473Z","steps":["trace[2019963130] 'range keys from in-memory index tree' (duration: 123.555587ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.44004Z","caller":"traceutil/trace.go:171","msg":"trace[1989338797] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"318.725325ms","start":"2026-08-22T18:40:45.121192Z","end":"2026-08-22T18:40:45.439918Z","steps":["trace[1989338797] 'read index received' (duration: 318.503634ms)","trace[1989338797] 'applied index is now lower than readState.Index' (duration: 220.671µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:45.440103Z","caller":"traceutil/trace.go:171","msg":"trace[263299708] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"321.021674ms","start":"2026-08-22T18:40:45.119037Z","end":"2026-08-22T18:40:45.440058Z","steps":["trace[263299708] 'process raft request' (duration: 320.725278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.4403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:45.11902Z","time spent":"321.180892ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4316,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:40:45.440351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.131396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T18:40:45.440469Z","caller":"traceutil/trace.go:171","msg":"trace[517110052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"319.255822ms","start":"2026-08-22T18:40:45.121168Z","end":"2026-08-22T18:40:45.440424Z","steps":["trace[517110052] 'agreement among raft nodes before linearized reading' (duration: 318.980568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.440521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:45.121157Z","time spent":"319.353708ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T18:40:45.440425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.128262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-22T18:40:45.440729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.836127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:45.440727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.391837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:45.440786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.464396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:40:45.440783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.620648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.440833Z","caller":"traceutil/trace.go:171","msg":"trace[1376374723] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:735; }","duration":"113.568175ms","start":"2026-08-22T18:40:45.327243Z","end":"2026-08-22T18:40:45.440812Z","steps":["trace[1376374723] 'agreement among raft nodes before linearized reading' (duration: 113.386526ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.44084Z","caller":"traceutil/trace.go:171","msg":"trace[1589278850] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:735; }","duration":"263.550251ms","start":"2026-08-22T18:40:45.177269Z","end":"2026-08-22T18:40:45.440819Z","steps":["trace[1589278850] 'agreement among raft nodes before linearized reading' (duration: 263.471567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.440853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.816736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.440859Z","caller":"traceutil/trace.go:171","msg":"trace[886555557] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:735; }","duration":"113.724874ms","start":"2026-08-22T18:40:45.327117Z","end":"2026-08-22T18:40:45.440842Z","steps":["trace[886555557] 'agreement among raft nodes before linearized reading' (duration: 113.601877ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.440886Z","caller":"traceutil/trace.go:171","msg":"trace[1020919711] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:735; }","duration":"264.871109ms","start":"2026-08-22T18:40:45.176006Z","end":"2026-08-22T18:40:45.440878Z","steps":["trace[1020919711] 'agreement among raft nodes before linearized reading' (duration: 264.820986ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.440796Z","caller":"traceutil/trace.go:171","msg":"trace[314652004] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:735; }","duration":"264.928621ms","start":"2026-08-22T18:40:45.175845Z","end":"2026-08-22T18:40:45.440774Z","steps":["trace[314652004] 'agreement among raft nodes before linearized reading' (duration: 264.824826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.440692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.695412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.441006Z","caller":"traceutil/trace.go:171","msg":"trace[646030820] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:735; }","duration":"129.059732ms","start":"2026-08-22T18:40:45.311934Z","end":"2026-08-22T18:40:45.440994Z","steps":["trace[646030820] 'agreement among raft nodes before linearized reading' (duration: 128.672262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.441012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.511447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.441061Z","caller":"traceutil/trace.go:171","msg":"trace[512962165] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:735; }","duration":"298.607452ms","start":"2026-08-22T18:40:45.142436Z","end":"2026-08-22T18:40:45.441044Z","steps":["trace[512962165] 'agreement among raft nodes before linearized reading' (duration: 298.531598ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:45.440769Z","caller":"traceutil/trace.go:171","msg":"trace[1679232889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"316.498441ms","start":"2026-08-22T18:40:45.124256Z","end":"2026-08-22T18:40:45.440755Z","steps":["trace[1679232889] 'agreement among raft nodes before linearized reading' (duration: 316.034697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.441391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.442673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.441433Z","caller":"traceutil/trace.go:171","msg":"trace[919713180] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:735; }","duration":"298.521327ms","start":"2026-08-22T18:40:45.142898Z","end":"2026-08-22T18:40:45.44142Z","steps":["trace[919713180] 'agreement among raft nodes before linearized reading' (duration: 298.434553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.44146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:45.12425Z","time spent":"317.155655ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T18:40:45.441151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.709603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.441534Z","caller":"traceutil/trace.go:171","msg":"trace[1523832476] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:735; }","duration":"114.126614ms","start":"2026-08-22T18:40:45.327399Z","end":"2026-08-22T18:40:45.441525Z","steps":["trace[1523832476] 'agreement among raft nodes before linearized reading' (duration: 113.704692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.441162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.741779ms","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-08-22T18:40:45.441668Z","caller":"traceutil/trace.go:171","msg":"trace[1218235133] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:735; }","duration":"299.308638ms","start":"2026-08-22T18:40:45.14235Z","end":"2026-08-22T18:40:45.441659Z","steps":["trace[1218235133] 'agreement among raft nodes before linearized reading' (duration: 298.668105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.441223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.669211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.44179Z","caller":"traceutil/trace.go:171","msg":"trace[1637437886] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:735; }","duration":"129.251891ms","start":"2026-08-22T18:40:45.31253Z","end":"2026-08-22T18:40:45.441782Z","steps":["trace[1637437886] 'agreement among raft nodes before linearized reading' (duration: 128.600988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.894985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.234264ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:40:45.89517Z","caller":"traceutil/trace.go:171","msg":"trace[1833982818] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:738; }","duration":"125.438344ms","start":"2026-08-22T18:40:45.769704Z","end":"2026-08-22T18:40:45.895142Z","steps":["trace[1833982818] 'range keys from in-memory index tree' (duration: 125.208543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:45.895228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.261062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-22T18:40:45.895802Z","caller":"traceutil/trace.go:171","msg":"trace[236253964] transaction","detail":"{read_only:false; number_of_response:0; response_revision:738; }","duration":"326.638122ms","start":"2026-08-22T18:40:45.569137Z","end":"2026-08-22T18:40:45.895775Z","steps":["trace[236253964] 'process raft request' (duration: 79.697235ms)","trace[236253964] 'compare' (duration: 246.22024ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:45.89617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:45.569119Z","time spent":"326.913496ms","remote":"127.0.0.1:57662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:40:46.467939Z","caller":"traceutil/trace.go:171","msg":"trace[956454365] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"112.154389ms","start":"2026-08-22T18:40:46.35575Z","end":"2026-08-22T18:40:46.467905Z","steps":["trace[956454365] 'read index received' (duration: 111.866984ms)","trace[956454365] 'applied index is now lower than readState.Index' (duration: 286.255µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:46.467992Z","caller":"traceutil/trace.go:171","msg":"trace[568799886] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"251.927292ms","start":"2026-08-22T18:40:46.216036Z","end":"2026-08-22T18:40:46.467964Z","steps":["trace[568799886] 'process raft request' (duration: 251.68436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:46.468211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.444424ms","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-08-22T18:40:46.468253Z","caller":"traceutil/trace.go:171","msg":"trace[1523397860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"112.507077ms","start":"2026-08-22T18:40:46.355735Z","end":"2026-08-22T18:40:46.468242Z","steps":["trace[1523397860] 'agreement among raft nodes before linearized reading' (duration: 112.328308ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:46.740881Z","caller":"traceutil/trace.go:171","msg":"trace[1108941867] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"138.650366ms","start":"2026-08-22T18:40:46.602202Z","end":"2026-08-22T18:40:46.740852Z","steps":["trace[1108941867] 'read index received' (duration: 45.505783ms)","trace[1108941867] 'applied index is now lower than readState.Index' (duration: 93.143253ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:46.740915Z","caller":"traceutil/trace.go:171","msg":"trace[1893225485] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"146.899755ms","start":"2026-08-22T18:40:46.593993Z","end":"2026-08-22T18:40:46.740892Z","steps":["trace[1893225485] 'process raft request' (duration: 53.821605ms)","trace[1893225485] 'compare' (duration: 92.87599ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:46.741172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.960741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2074"} {"level":"info","ts":"2026-08-22T18:40:46.741264Z","caller":"traceutil/trace.go:171","msg":"trace[971701152] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:745; }","duration":"139.113889ms","start":"2026-08-22T18:40:46.602137Z","end":"2026-08-22T18:40:46.741251Z","steps":["trace[971701152] 'agreement among raft nodes before linearized reading' (duration: 138.825945ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:40:46.93189Z","caller":"traceutil/trace.go:171","msg":"trace[1820262497] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"125.271786ms","start":"2026-08-22T18:40:46.806578Z","end":"2026-08-22T18:40:46.93185Z","steps":["trace[1820262497] 'process raft request' (duration: 108.392178ms)","trace[1820262497] 'compare' (duration: 16.785363ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:40:53.779428Z","caller":"traceutil/trace.go:171","msg":"trace[1393534288] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"314.691009ms","start":"2026-08-22T18:40:53.464709Z","end":"2026-08-22T18:40:53.7794Z","steps":["trace[1393534288] 'process raft request' (duration: 314.188565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:53.779623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:40:53.464685Z","time spent":"314.834596ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:40:53.779434Z","caller":"traceutil/trace.go:171","msg":"trace[460792759] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"206.299901ms","start":"2026-08-22T18:40:53.57278Z","end":"2026-08-22T18:40:53.77908Z","steps":["trace[460792759] 'read index received' (duration: 206.101042ms)","trace[460792759] 'applied index is now lower than readState.Index' (duration: 198.159µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:40:53.779795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.958613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T18:40:53.7799Z","caller":"traceutil/trace.go:171","msg":"trace[1622948456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"207.140651ms","start":"2026-08-22T18:40:53.572735Z","end":"2026-08-22T18:40:53.779876Z","steps":["trace[1622948456] 'agreement among raft nodes before linearized reading' (duration: 206.746863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:40:53.780172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.245716ms","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-08-22T18:40:53.780277Z","caller":"traceutil/trace.go:171","msg":"trace[1979093176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"207.442895ms","start":"2026-08-22T18:40:53.572814Z","end":"2026-08-22T18:40:53.780257Z","steps":["trace[1979093176] 'agreement among raft nodes before linearized reading' (duration: 207.136521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:41:36.252956Z","caller":"traceutil/trace.go:171","msg":"trace[2111593270] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"135.707529ms","start":"2026-08-22T18:41:36.117219Z","end":"2026-08-22T18:41:36.252927Z","steps":["trace[2111593270] 'process raft request' (duration: 97.556006ms)","trace[2111593270] 'compare' (duration: 38.024529ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:41:36.936687Z","caller":"traceutil/trace.go:171","msg":"trace[1071467673] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"120.410337ms","start":"2026-08-22T18:41:36.816251Z","end":"2026-08-22T18:41:36.936662Z","steps":["trace[1071467673] 'read index received' (duration: 120.18544ms)","trace[1071467673] 'applied index is now lower than readState.Index' (duration: 223.597µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:41:36.936831Z","caller":"traceutil/trace.go:171","msg":"trace[1034700517] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"164.480647ms","start":"2026-08-22T18:41:36.772307Z","end":"2026-08-22T18:41:36.936788Z","steps":["trace[1034700517] 'process raft request' (duration: 164.213349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:41:36.936953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.694326ms","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-08-22T18:41:36.937001Z","caller":"traceutil/trace.go:171","msg":"trace[722685846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"120.789819ms","start":"2026-08-22T18:41:36.816195Z","end":"2026-08-22T18:41:36.936985Z","steps":["trace[722685846] 'agreement among raft nodes before linearized reading' (duration: 120.594263ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:41:58.327403Z","caller":"traceutil/trace.go:171","msg":"trace[787177529] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"128.286111ms","start":"2026-08-22T18:41:58.199092Z","end":"2026-08-22T18:41:58.327378Z","steps":["trace[787177529] 'process raft request' (duration: 128.119166ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:42:40.919278Z","caller":"traceutil/trace.go:171","msg":"trace[1716898099] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"184.966912ms","start":"2026-08-22T18:42:40.734289Z","end":"2026-08-22T18:42:40.919256Z","steps":["trace[1716898099] 'read index received' (duration: 184.766908ms)","trace[1716898099] 'applied index is now lower than readState.Index' (duration: 199.214µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:42:40.919366Z","caller":"traceutil/trace.go:171","msg":"trace[69295834] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"277.542945ms","start":"2026-08-22T18:42:40.641798Z","end":"2026-08-22T18:42:40.919341Z","steps":["trace[69295834] 'process raft request' (duration: 277.273739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:42:40.919401Z","caller":"traceutil/trace.go:171","msg":"trace[1351683621] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"225.833672ms","start":"2026-08-22T18:42:40.693534Z","end":"2026-08-22T18:42:40.919368Z","steps":["trace[1351683621] 'process raft request' (duration: 225.680239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:42:40.919466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.171476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:42:40.919521Z","caller":"traceutil/trace.go:171","msg":"trace[1811965115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"185.265688ms","start":"2026-08-22T18:42:40.734231Z","end":"2026-08-22T18:42:40.919497Z","steps":["trace[1811965115] 'agreement among raft nodes before linearized reading' (duration: 185.115295ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:42:48.830276Z","caller":"traceutil/trace.go:171","msg":"trace[1427398217] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"263.159445ms","start":"2026-08-22T18:42:48.567077Z","end":"2026-08-22T18:42:48.830236Z","steps":["trace[1427398217] 'process raft request' (duration: 262.600483ms)"],"step_count":1}