{"level":"warn","ts":"2026-04-22T06:04:46.358014Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:04:46.358083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T06:04:46.358136Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:04:46.358142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-22T06:04:46.358157Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:04:46.358736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-04-22T06:04:46.358897Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T06:04:46.36301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.927048ms"} {"level":"info","ts":"2026-04-22T06:04:46.370276Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-04-22T06:04:46.370432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:04:46.370505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-04-22T06:04:46.370533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:04:46.370562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-04-22T06:04:46.370614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-04-22T06:04:46.379327Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:04:46.380801Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:04:46.381911Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T06:04:46.38469Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:04:46.384939Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:04:46.385174Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:46.385238Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:46.385249Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:04:46.385776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-04-22T06:04:46.385876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-22T06:04:46.389803Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:04:46.389895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-22T06:04:46.389931Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-22T06:04:46.390092Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:04:46.390147Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:04:46.971748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:04:46.971805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:04:46.971845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-04-22T06:04:46.971864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:04:46.971882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-22T06:04:46.971898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-04-22T06:04:46.971917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-22T06:04:46.972838Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:46.974005Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:46.974088Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:04:46.974111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:46.974143Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:04:46.974163Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:04:46.974212Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:04:46.976349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:04:46.978322Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:04:46.978368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:04:46.983185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"warn","ts":"2026-04-22T06:04:49.692695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.498988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:04:49.692822Z","caller":"traceutil/trace.go:171","msg":"trace[1582397957] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"303.661625ms","start":"2026-04-22T06:04:49.389142Z","end":"2026-04-22T06:04:49.692804Z","steps":["trace[1582397957] 'process raft request' (duration: 30.361121ms)","trace[1582397957] 'compare' (duration: 272.329645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:49.692898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:49.389132Z","time spent":"303.742286ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1063,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:04:50.111035Z","caller":"traceutil/trace.go:171","msg":"trace[173500487] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:165; }","duration":"292.378939ms","start":"2026-04-22T06:04:49.818626Z","end":"2026-04-22T06:04:50.111005Z","steps":["trace[173500487] 'read index received' (duration: 235.180157ms)","trace[173500487] 'applied index is now lower than readState.Index' (duration: 57.197782ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:04:50.111154Z","caller":"traceutil/trace.go:171","msg":"trace[1824331654] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"412.863855ms","start":"2026-04-22T06:04:49.698251Z","end":"2026-04-22T06:04:50.111115Z","steps":["trace[1824331654] 'process raft request' (duration: 355.645823ms)","trace[1824331654] 'compare' (duration: 56.968127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:50.111352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.698895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:04:50.111459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:49.698201Z","time spent":"413.078889ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":650,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:04:50.111492Z","caller":"traceutil/trace.go:171","msg":"trace[1390552315] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:161; }","duration":"292.806316ms","start":"2026-04-22T06:04:49.818584Z","end":"2026-04-22T06:04:50.11139Z","steps":["trace[1390552315] 'agreement among raft nodes before linearized reading' (duration: 292.524811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:50.45661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.796972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:04:50.457459Z","caller":"traceutil/trace.go:171","msg":"trace[308394865] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:166; }","duration":"212.141592ms","start":"2026-04-22T06:04:50.245283Z","end":"2026-04-22T06:04:50.457425Z","steps":["trace[308394865] 'read index received' (duration: 95.479412ms)","trace[308394865] 'applied index is now lower than readState.Index' (duration: 116.66117ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:04:50.457529Z","caller":"traceutil/trace.go:171","msg":"trace[330933397] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"340.945797ms","start":"2026-04-22T06:04:50.116562Z","end":"2026-04-22T06:04:50.457507Z","steps":["trace[330933397] 'process raft request' (duration: 224.189225ms)","trace[330933397] 'compare' (duration: 115.66962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:50.457587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.277846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:04:50.457629Z","caller":"traceutil/trace.go:171","msg":"trace[1361516133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:163; }","duration":"212.390849ms","start":"2026-04-22T06:04:50.245228Z","end":"2026-04-22T06:04:50.457619Z","steps":["trace[1361516133] 'agreement among raft nodes before linearized reading' (duration: 212.269677ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:50.457495Z","caller":"traceutil/trace.go:171","msg":"trace[1785189670] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"340.511189ms","start":"2026-04-22T06:04:50.116961Z","end":"2026-04-22T06:04:50.457472Z","steps":["trace[1785189670] 'process raft request' (duration: 340.363495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:50.457691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:50.116549Z","time spent":"341.075109ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:04:50.457781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:50.116944Z","time spent":"340.761244ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:05:16.226554Z","caller":"traceutil/trace.go:171","msg":"trace[73377141] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"132.731614ms","start":"2026-04-22T06:05:16.093803Z","end":"2026-04-22T06:05:16.226534Z","steps":["trace[73377141] 'process raft request' (duration: 132.604811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:17.19108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.002365ms","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-04-22T06:05:17.191182Z","caller":"traceutil/trace.go:171","msg":"trace[1914104959] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:508; }","duration":"168.159281ms","start":"2026-04-22T06:05:17.023006Z","end":"2026-04-22T06:05:17.191165Z","steps":["trace[1914104959] 'range keys from in-memory index tree' (duration: 167.902502ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.548526Z","caller":"traceutil/trace.go:171","msg":"trace[249178369] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"179.446372ms","start":"2026-04-22T06:05:17.369052Z","end":"2026-04-22T06:05:17.548498Z","steps":["trace[249178369] 'process raft request' (duration: 179.232037ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556464Z","caller":"traceutil/trace.go:171","msg":"trace[66684900] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"160.567359ms","start":"2026-04-22T06:05:17.395876Z","end":"2026-04-22T06:05:17.556444Z","steps":["trace[66684900] 'process raft request' (duration: 160.275962ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556656Z","caller":"traceutil/trace.go:171","msg":"trace[552032377] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"160.823366ms","start":"2026-04-22T06:05:17.395824Z","end":"2026-04-22T06:05:17.556648Z","steps":["trace[552032377] 'process raft request' (duration: 160.217641ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556645Z","caller":"traceutil/trace.go:171","msg":"trace[1581521108] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"133.570294ms","start":"2026-04-22T06:05:17.423044Z","end":"2026-04-22T06:05:17.556614Z","steps":["trace[1581521108] 'process raft request' (duration: 133.185464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556772Z","caller":"traceutil/trace.go:171","msg":"trace[1385390072] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"133.645286ms","start":"2026-04-22T06:05:17.423081Z","end":"2026-04-22T06:05:17.556726Z","steps":["trace[1385390072] 'process raft request' (duration: 133.184704ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556874Z","caller":"traceutil/trace.go:171","msg":"trace[1525550493] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"140.345124ms","start":"2026-04-22T06:05:17.416336Z","end":"2026-04-22T06:05:17.556681Z","steps":["trace[1525550493] 'process raft request' (duration: 139.853561ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.557135Z","caller":"traceutil/trace.go:171","msg":"trace[1483115719] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"124.219269ms","start":"2026-04-22T06:05:17.432902Z","end":"2026-04-22T06:05:17.557121Z","steps":["trace[1483115719] 'process raft request' (duration: 123.934582ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.557164Z","caller":"traceutil/trace.go:171","msg":"trace[433790317] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"121.667505ms","start":"2026-04-22T06:05:17.435482Z","end":"2026-04-22T06:05:17.55715Z","steps":["trace[433790317] 'process raft request' (duration: 121.411329ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.557201Z","caller":"traceutil/trace.go:171","msg":"trace[360917610] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"105.83889ms","start":"2026-04-22T06:05:17.451355Z","end":"2026-04-22T06:05:17.557194Z","steps":["trace[360917610] 'process raft request' (duration: 105.596994ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.557347Z","caller":"traceutil/trace.go:171","msg":"trace[1004416112] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"132.429995ms","start":"2026-04-22T06:05:17.424513Z","end":"2026-04-22T06:05:17.556943Z","steps":["trace[1004416112] 'process raft request' (duration: 131.83904ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:17.556828Z","caller":"traceutil/trace.go:171","msg":"trace[1128685096] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"132.664982ms","start":"2026-04-22T06:05:17.424152Z","end":"2026-04-22T06:05:17.556817Z","steps":["trace[1128685096] 'process raft request' (duration: 132.153069ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:20.022952Z","caller":"traceutil/trace.go:171","msg":"trace[1710180499] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"244.016169ms","start":"2026-04-22T06:05:19.778908Z","end":"2026-04-22T06:05:20.022924Z","steps":["trace[1710180499] 'process raft request' (duration: 243.698011ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:21.307264Z","caller":"traceutil/trace.go:171","msg":"trace[1348238172] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"154.229161ms","start":"2026-04-22T06:05:21.153004Z","end":"2026-04-22T06:05:21.307233Z","steps":["trace[1348238172] 'process raft request' (duration: 153.542143ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:24.160978Z","caller":"traceutil/trace.go:171","msg":"trace[1110202263] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"115.251596ms","start":"2026-04-22T06:05:24.045698Z","end":"2026-04-22T06:05:24.160949Z","steps":["trace[1110202263] 'read index received' (duration: 114.331103ms)","trace[1110202263] 'applied index is now lower than readState.Index' (duration: 919.333µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:24.161034Z","caller":"traceutil/trace.go:171","msg":"trace[19055519] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"396.898386ms","start":"2026-04-22T06:05:23.764078Z","end":"2026-04-22T06:05:24.160977Z","steps":["trace[19055519] 'process raft request' (duration: 396.164868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:24.161161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.44538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:05:24.161196Z","caller":"traceutil/trace.go:171","msg":"trace[451824940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"115.532802ms","start":"2026-04-22T06:05:24.045653Z","end":"2026-04-22T06:05:24.161186Z","steps":["trace[451824940] 'agreement among raft nodes before linearized reading' (duration: 115.44348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:24.162992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:23.764048Z","time spent":"397.106731ms","remote":"127.0.0.1:49330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:24.29118Z","caller":"traceutil/trace.go:171","msg":"trace[1881155555] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"123.706606ms","start":"2026-04-22T06:05:24.167429Z","end":"2026-04-22T06:05:24.291135Z","steps":["trace[1881155555] 'process raft request' (duration: 71.012088ms)","trace[1881155555] 'compare' (duration: 52.470413ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:24.291205Z","caller":"traceutil/trace.go:171","msg":"trace[1560313281] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"123.099172ms","start":"2026-04-22T06:05:24.16806Z","end":"2026-04-22T06:05:24.29116Z","steps":["trace[1560313281] 'process raft request' (duration: 122.955499ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:25.47779Z","caller":"traceutil/trace.go:171","msg":"trace[250000376] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"501.811513ms","start":"2026-04-22T06:05:24.975929Z","end":"2026-04-22T06:05:25.477741Z","steps":["trace[250000376] 'read index received' (duration: 493.555577ms)","trace[250000376] 'applied index is now lower than readState.Index' (duration: 8.254996ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:25.477874Z","caller":"traceutil/trace.go:171","msg":"trace[1964124941] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"773.426412ms","start":"2026-04-22T06:05:24.704424Z","end":"2026-04-22T06:05:25.477851Z","steps":["trace[1964124941] 'process raft request' (duration: 767.662888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:25.477973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.040848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T06:05:25.477993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:24.704395Z","time spent":"773.531645ms","remote":"127.0.0.1:49330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:25.478001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.218927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:05:25.478002Z","caller":"traceutil/trace.go:171","msg":"trace[2073201923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"502.11772ms","start":"2026-04-22T06:05:24.975875Z","end":"2026-04-22T06:05:25.477992Z","steps":["trace[2073201923] 'agreement among raft nodes before linearized reading' (duration: 502.002588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:25.47806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:24.975853Z","time spent":"502.201802ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:05:25.478068Z","caller":"traceutil/trace.go:171","msg":"trace[2030866229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"197.33353ms","start":"2026-04-22T06:05:25.280714Z","end":"2026-04-22T06:05:25.478048Z","steps":["trace[2030866229] 'agreement among raft nodes before linearized reading' (duration: 197.234457ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:28.999772Z","caller":"traceutil/trace.go:171","msg":"trace[914048865] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"106.193378ms","start":"2026-04-22T06:05:28.893553Z","end":"2026-04-22T06:05:28.999746Z","steps":["trace[914048865] 'read index received' (duration: 106.009423ms)","trace[914048865] 'applied index is now lower than readState.Index' (duration: 182.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:28.999881Z","caller":"traceutil/trace.go:171","msg":"trace[121056387] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"260.231034ms","start":"2026-04-22T06:05:28.739636Z","end":"2026-04-22T06:05:28.999867Z","steps":["trace[121056387] 'process raft request' (duration: 259.983058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:29.00001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.430874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:05:29.000119Z","caller":"traceutil/trace.go:171","msg":"trace[1279458404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"106.594638ms","start":"2026-04-22T06:05:28.893499Z","end":"2026-04-22T06:05:29.000093Z","steps":["trace[1279458404] 'agreement among raft nodes before linearized reading' (duration: 106.411533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:40.871372Z","caller":"traceutil/trace.go:171","msg":"trace[1574200819] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"498.993922ms","start":"2026-04-22T06:05:40.372355Z","end":"2026-04-22T06:05:40.871349Z","steps":["trace[1574200819] 'read index received' (duration: 498.732656ms)","trace[1574200819] 'applied index is now lower than readState.Index' (duration: 260.066µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:40.871494Z","caller":"traceutil/trace.go:171","msg":"trace[501709580] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"500.958902ms","start":"2026-04-22T06:05:40.370496Z","end":"2026-04-22T06:05:40.871455Z","steps":["trace[501709580] 'process raft request' (duration: 500.704746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:40.871667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:40.37048Z","time spent":"501.089255ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:40.871698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.32592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:05:40.871734Z","caller":"traceutil/trace.go:171","msg":"trace[2098613341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"499.381881ms","start":"2026-04-22T06:05:40.372342Z","end":"2026-04-22T06:05:40.871723Z","steps":["trace[2098613341] 'agreement among raft nodes before linearized reading' (duration: 499.234128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:40.87177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:40.372297Z","time spent":"499.464633ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:05:40.871755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.126975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:05:40.87181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.581822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-n9tzl\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T06:05:40.871853Z","caller":"traceutil/trace.go:171","msg":"trace[1950277414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"499.223608ms","start":"2026-04-22T06:05:40.372587Z","end":"2026-04-22T06:05:40.871811Z","steps":["trace[1950277414] 'agreement among raft nodes before linearized reading' (duration: 499.044663ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:40.871869Z","caller":"traceutil/trace.go:171","msg":"trace[1597886739] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-n9tzl; range_end:; response_count:1; response_revision:778; }","duration":"176.682073ms","start":"2026-04-22T06:05:40.695168Z","end":"2026-04-22T06:05:40.87185Z","steps":["trace[1597886739] 'agreement among raft nodes before linearized reading' (duration: 176.507968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:40.871899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:40.372582Z","time spent":"499.30381ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:05:40.872353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.158873ms","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-04-22T06:05:40.872401Z","caller":"traceutil/trace.go:171","msg":"trace[1324077351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"119.249275ms","start":"2026-04-22T06:05:40.753141Z","end":"2026-04-22T06:05:40.87239Z","steps":["trace[1324077351] 'agreement among raft nodes before linearized reading' (duration: 118.488236ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:41.019198Z","caller":"traceutil/trace.go:171","msg":"trace[1840712100] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"143.128063ms","start":"2026-04-22T06:05:40.876051Z","end":"2026-04-22T06:05:41.019179Z","steps":["trace[1840712100] 'process raft request' (duration: 142.97537ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:41.019397Z","caller":"traceutil/trace.go:171","msg":"trace[1493598988] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"143.044401ms","start":"2026-04-22T06:05:40.876338Z","end":"2026-04-22T06:05:41.019383Z","steps":["trace[1493598988] 'process raft request' (duration: 142.805735ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:41.019831Z","caller":"traceutil/trace.go:171","msg":"trace[241013056] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"143.00902ms","start":"2026-04-22T06:05:40.876809Z","end":"2026-04-22T06:05:41.019818Z","steps":["trace[241013056] 'process raft request' (duration: 142.526248ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:41.019966Z","caller":"traceutil/trace.go:171","msg":"trace[1090583425] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"141.239126ms","start":"2026-04-22T06:05:40.878712Z","end":"2026-04-22T06:05:41.019951Z","steps":["trace[1090583425] 'process raft request' (duration: 140.866558ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:15.288956Z","caller":"traceutil/trace.go:171","msg":"trace[1104573607] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"111.061147ms","start":"2026-04-22T06:06:15.177865Z","end":"2026-04-22T06:06:15.288927Z","steps":["trace[1104573607] 'read index received' (duration: 91.502506ms)","trace[1104573607] 'applied index is now lower than readState.Index' (duration: 19.557601ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:15.289181Z","caller":"traceutil/trace.go:171","msg":"trace[266646385] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"117.458612ms","start":"2026-04-22T06:06:15.171701Z","end":"2026-04-22T06:06:15.289159Z","steps":["trace[266646385] 'process raft request' (duration: 117.038141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:15.289209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.331395ms","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-04-22T06:06:15.289306Z","caller":"traceutil/trace.go:171","msg":"trace[1520130321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"111.467618ms","start":"2026-04-22T06:06:15.177826Z","end":"2026-04-22T06:06:15.289294Z","steps":["trace[1520130321] 'agreement among raft nodes before linearized reading' (duration: 111.214211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:15.644145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.173703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:06:15.644245Z","caller":"traceutil/trace.go:171","msg":"trace[1955659983] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"350.221381ms","start":"2026-04-22T06:06:15.294007Z","end":"2026-04-22T06:06:15.644228Z","steps":["trace[1955659983] 'process raft request' (duration: 156.888774ms)","trace[1955659983] 'compare' (duration: 193.05381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:15.644437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:15.293934Z","time spent":"350.467148ms","remote":"127.0.0.1:49520","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-04-22T06:06:15.843111Z","caller":"traceutil/trace.go:171","msg":"trace[224100813] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"110.630527ms","start":"2026-04-22T06:06:15.732455Z","end":"2026-04-22T06:06:15.843086Z","steps":["trace[224100813] 'process raft request' (duration: 110.503094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:39.432439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.607021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:39.432748Z","caller":"traceutil/trace.go:171","msg":"trace[424614606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"148.747583ms","start":"2026-04-22T06:06:39.283758Z","end":"2026-04-22T06:06:39.432505Z","steps":["trace[424614606] 'range keys from in-memory index tree' (duration: 148.481648ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:39.588309Z","caller":"traceutil/trace.go:171","msg":"trace[1054579909] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"120.367137ms","start":"2026-04-22T06:06:39.46792Z","end":"2026-04-22T06:06:39.588287Z","steps":["trace[1054579909] 'read index received' (duration: 120.309776ms)","trace[1054579909] 'applied index is now lower than readState.Index' (duration: 56.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:39.588409Z","caller":"traceutil/trace.go:171","msg":"trace[849519069] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"137.143727ms","start":"2026-04-22T06:06:39.451223Z","end":"2026-04-22T06:06:39.588367Z","steps":["trace[849519069] 'process raft request' (duration: 136.911181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:39.588713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.768658ms","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-04-22T06:06:39.588767Z","caller":"traceutil/trace.go:171","msg":"trace[937863334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"120.863179ms","start":"2026-04-22T06:06:39.467892Z","end":"2026-04-22T06:06:39.588755Z","steps":["trace[937863334] 'agreement among raft nodes before linearized reading' (duration: 120.539571ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:51.734988Z","caller":"traceutil/trace.go:171","msg":"trace[1689063591] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"450.523283ms","start":"2026-04-22T06:06:51.284438Z","end":"2026-04-22T06:06:51.734961Z","steps":["trace[1689063591] 'read index received' (duration: 450.382219ms)","trace[1689063591] 'applied index is now lower than readState.Index' (duration: 140.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:51.735017Z","caller":"traceutil/trace.go:171","msg":"trace[1090360250] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"468.178537ms","start":"2026-04-22T06:06:51.266824Z","end":"2026-04-22T06:06:51.735003Z","steps":["trace[1090360250] 'process raft request' (duration: 468.010673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:51.735104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.654547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:06:51.735138Z","caller":"traceutil/trace.go:171","msg":"trace[1028911640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"450.74387ms","start":"2026-04-22T06:06:51.284384Z","end":"2026-04-22T06:06:51.735128Z","steps":["trace[1028911640] 'agreement among raft nodes before linearized reading' (duration: 450.666848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:51.735149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:51.266775Z","time spent":"468.31881ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:06:51.735164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:51.284363Z","time spent":"450.79418ms","remote":"127.0.0.1:49228","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-04-22T06:06:51.735177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.274087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:06:51.735215Z","caller":"traceutil/trace.go:171","msg":"trace[1400501280] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1168; }","duration":"227.358279ms","start":"2026-04-22T06:06:51.507846Z","end":"2026-04-22T06:06:51.735204Z","steps":["trace[1400501280] 'agreement among raft nodes before linearized reading' (duration: 227.284368ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:51.9702Z","caller":"traceutil/trace.go:171","msg":"trace[1911347661] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"105.065294ms","start":"2026-04-22T06:06:51.865117Z","end":"2026-04-22T06:06:51.970183Z","steps":["trace[1911347661] 'read index received' (duration: 103.175165ms)","trace[1911347661] 'applied index is now lower than readState.Index' (duration: 1.889329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:06:51.970347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.219839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:06:51.970367Z","caller":"traceutil/trace.go:171","msg":"trace[531712587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"105.28935ms","start":"2026-04-22T06:06:51.865072Z","end":"2026-04-22T06:06:51.970361Z","steps":["trace[531712587] 'agreement among raft nodes before linearized reading' (duration: 105.175268ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:51.970377Z","caller":"traceutil/trace.go:171","msg":"trace[417798339] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"231.798585ms","start":"2026-04-22T06:06:51.738535Z","end":"2026-04-22T06:06:51.970333Z","steps":["trace[417798339] 'process raft request' (duration: 229.739132ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:51.970454Z","caller":"traceutil/trace.go:171","msg":"trace[936123600] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"231.593188ms","start":"2026-04-22T06:06:51.738845Z","end":"2026-04-22T06:06:51.970438Z","steps":["trace[936123600] 'process raft request' (duration: 231.280071ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:03.92661Z","caller":"traceutil/trace.go:171","msg":"trace[1550651611] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"140.826552ms","start":"2026-04-22T06:07:03.78576Z","end":"2026-04-22T06:07:03.926587Z","steps":["trace[1550651611] 'process raft request' (duration: 140.662208ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:11.044863Z","caller":"traceutil/trace.go:171","msg":"trace[1777725461] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"253.003419ms","start":"2026-04-22T06:07:10.791836Z","end":"2026-04-22T06:07:11.044839Z","steps":["trace[1777725461] 'process raft request' (duration: 252.858656ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:11.045314Z","caller":"traceutil/trace.go:171","msg":"trace[1700269794] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1318; }","duration":"192.764414ms","start":"2026-04-22T06:07:10.852538Z","end":"2026-04-22T06:07:11.045303Z","steps":["trace[1700269794] 'read index received' (duration: 192.760184ms)","trace[1700269794] 'applied index is now lower than readState.Index' (duration: 3.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:11.045532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.972489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T06:07:11.045586Z","caller":"traceutil/trace.go:171","msg":"trace[637460769] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:1267; }","duration":"193.080332ms","start":"2026-04-22T06:07:10.852494Z","end":"2026-04-22T06:07:11.045574Z","steps":["trace[637460769] 'agreement among raft nodes before linearized reading' (duration: 192.880197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:11.115682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.840768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:11.115728Z","caller":"traceutil/trace.go:171","msg":"trace[849187652] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"112.447664ms","start":"2026-04-22T06:07:11.003251Z","end":"2026-04-22T06:07:11.115698Z","steps":["trace[849187652] 'process raft request' (duration: 112.147197ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:11.115764Z","caller":"traceutil/trace.go:171","msg":"trace[1078618045] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1268; }","duration":"226.96834ms","start":"2026-04-22T06:07:10.888775Z","end":"2026-04-22T06:07:11.115743Z","steps":["trace[1078618045] 'agreement among raft nodes before linearized reading' (duration: 226.804766ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:21.268186Z","caller":"traceutil/trace.go:171","msg":"trace[365339463] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"210.128564ms","start":"2026-04-22T06:07:21.058035Z","end":"2026-04-22T06:07:21.268163Z","steps":["trace[365339463] 'read index received' (duration: 210.085683ms)","trace[365339463] 'applied index is now lower than readState.Index' (duration: 41.621µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:21.268232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:20.85454Z","time spent":"413.684071ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T06:07:21.26839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.343951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-22T06:07:21.268437Z","caller":"traceutil/trace.go:171","msg":"trace[1255383124] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1333; }","duration":"210.477004ms","start":"2026-04-22T06:07:21.057949Z","end":"2026-04-22T06:07:21.268426Z","steps":["trace[1255383124] 'agreement among raft nodes before linearized reading' (duration: 210.32233ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:21.289632Z","caller":"traceutil/trace.go:171","msg":"trace[1972761633] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"119.234673ms","start":"2026-04-22T06:07:21.170381Z","end":"2026-04-22T06:07:21.289616Z","steps":["trace[1972761633] 'process raft request' (duration: 119.10772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.603132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.25475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:07:21.603416Z","caller":"traceutil/trace.go:171","msg":"trace[1292059997] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"309.581028ms","start":"2026-04-22T06:07:21.293761Z","end":"2026-04-22T06:07:21.603342Z","steps":["trace[1292059997] 'process raft request' (duration: 309.540377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.603613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:21.29374Z","time spent":"309.769073ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:21.603682Z","caller":"traceutil/trace.go:171","msg":"trace[210055016] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"331.707289ms","start":"2026-04-22T06:07:21.271947Z","end":"2026-04-22T06:07:21.603654Z","steps":["trace[210055016] 'process raft request' (duration: 331.293068ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:21.60367Z","caller":"traceutil/trace.go:171","msg":"trace[1695393880] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"334.221843ms","start":"2026-04-22T06:07:21.269405Z","end":"2026-04-22T06:07:21.603627Z","steps":["trace[1695393880] 'process raft request' (duration: 166.389608ms)","trace[1695393880] 'compare' (duration: 167.045205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:21.603795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.358441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:21.603845Z","caller":"traceutil/trace.go:171","msg":"trace[2144691483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"321.445904ms","start":"2026-04-22T06:07:21.282387Z","end":"2026-04-22T06:07:21.603833Z","steps":["trace[2144691483] 'agreement among raft nodes before linearized reading' (duration: 321.344681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:21.603845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:21.271921Z","time spent":"331.834401ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:21.603682Z","caller":"traceutil/trace.go:171","msg":"trace[1343671346] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1388; }","duration":"314.063844ms","start":"2026-04-22T06:07:21.289594Z","end":"2026-04-22T06:07:21.603658Z","steps":["trace[1343671346] 'read index received' (duration: 146.196888ms)","trace[1343671346] 'applied index is now lower than readState.Index' (duration: 167.864256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:07:21.603877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:21.282376Z","time spent":"321.494415ms","remote":"127.0.0.1:49228","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-04-22T06:07:21.603878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:21.269376Z","time spent":"334.404078ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}