{"level":"warn","ts":"2026-05-29T01:59:52.212977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T01:59:52.213043Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T01:59:52.213095Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T01:59:52.213102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-29T01:59:52.21312Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T01:59:52.213706Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-05-29T01:59:52.213772Z","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://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T01:59:52.217531Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.586642ms"} {"level":"info","ts":"2026-05-29T01:59:52.224302Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-05-29T01:59:52.224387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T01:59:52.224409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-05-29T01:59:52.224419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T01:59:52.224427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-05-29T01:59:52.224455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-05-29T01:59:52.229639Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T01:59:52.231203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T01:59:52.232174Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T01:59:52.234482Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T01:59:52.234705Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T01:59:52.234797Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T01:59:52.234855Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T01:59:52.234864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T01:59:52.236411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-05-29T01:59:52.236543Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-29T01:59:52.238657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-29T01:59:52.238688Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-29T01:59:52.238709Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T01:59:52.238926Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T01:59:52.238958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T01:59:53.125215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T01:59:53.125271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T01:59:53.125306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-05-29T01:59:53.12533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-05-29T01:59:53.125347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-29T01:59:53.125366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-05-29T01:59:53.125383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-29T01:59:53.12731Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T01:59:53.12775Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T01:59:53.127981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T01:59:53.128164Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T01:59:53.12821Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T01:59:53.128162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T01:59:53.128918Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T01:59:53.128991Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T01:59:53.129029Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T01:59:53.130788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T01:59:53.139925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-05-29T01:59:55.611847Z","caller":"traceutil/trace.go:171","msg":"trace[547764081] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"143.072261ms","start":"2026-05-29T01:59:55.468752Z","end":"2026-05-29T01:59:55.611824Z","steps":["trace[547764081] 'process raft request' (duration: 143.025559ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:55.6119Z","caller":"traceutil/trace.go:171","msg":"trace[1593307439] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"143.29758ms","start":"2026-05-29T01:59:55.468567Z","end":"2026-05-29T01:59:55.611864Z","steps":["trace[1593307439] 'process raft request' (duration: 46.31052ms)","trace[1593307439] 'compare' (duration: 96.73855ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:55.9212Z","caller":"traceutil/trace.go:171","msg":"trace[612521454] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"154.412566ms","start":"2026-05-29T01:59:55.76673Z","end":"2026-05-29T01:59:55.921143Z","steps":["trace[612521454] 'process raft request' (duration: 154.355944ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:55.921227Z","caller":"traceutil/trace.go:171","msg":"trace[616887957] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"155.666238ms","start":"2026-05-29T01:59:55.765494Z","end":"2026-05-29T01:59:55.92116Z","steps":["trace[616887957] 'process raft request' (duration: 106.586754ms)","trace[616887957] 'compare' (duration: 48.841954ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:56.338424Z","caller":"traceutil/trace.go:171","msg":"trace[1459432216] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"122.683749ms","start":"2026-05-29T01:59:56.215694Z","end":"2026-05-29T01:59:56.338378Z","steps":["trace[1459432216] 'process raft request' (duration: 75.582156ms)","trace[1459432216] 'compare' (duration: 46.840342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:56.610795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.030931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T01:59:56.610998Z","caller":"traceutil/trace.go:171","msg":"trace[482931460] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:115; }","duration":"185.658816ms","start":"2026-05-29T01:59:56.425323Z","end":"2026-05-29T01:59:56.610982Z","steps":["trace[482931460] 'read index received' (duration: 13.412895ms)","trace[482931460] 'applied index is now lower than readState.Index' (duration: 172.245111ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:56.611076Z","caller":"traceutil/trace.go:171","msg":"trace[1710218909] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"213.168892ms","start":"2026-05-29T01:59:56.397864Z","end":"2026-05-29T01:59:56.611033Z","steps":["trace[1710218909] 'process raft request' (duration: 40.862428ms)","trace[1710218909] 'compare' (duration: 170.891366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:56.61112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.795891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T01:59:56.611117Z","caller":"traceutil/trace.go:171","msg":"trace[1765977599] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"213.044427ms","start":"2026-05-29T01:59:56.398042Z","end":"2026-05-29T01:59:56.611086Z","steps":["trace[1765977599] 'process raft request' (duration: 212.87481ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:56.611157Z","caller":"traceutil/trace.go:171","msg":"trace[1938139435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"185.896744ms","start":"2026-05-29T01:59:56.425252Z","end":"2026-05-29T01:59:56.611148Z","steps":["trace[1938139435] 'agreement among raft nodes before linearized reading' (duration: 185.78804ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:57.14502Z","caller":"traceutil/trace.go:171","msg":"trace[675948624] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:131; }","duration":"178.073414ms","start":"2026-05-29T01:59:56.966913Z","end":"2026-05-29T01:59:57.144986Z","steps":["trace[675948624] 'read index received' (duration: 92.864104ms)","trace[675948624] 'applied index is now lower than readState.Index' (duration: 85.20785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:57.145266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.325524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T01:59:57.145315Z","caller":"traceutil/trace.go:171","msg":"trace[266763955] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:0; response_revision:127; }","duration":"178.411889ms","start":"2026-05-29T01:59:56.966889Z","end":"2026-05-29T01:59:57.145301Z","steps":["trace[266763955] 'agreement among raft nodes before linearized reading' (duration: 178.243382ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:57.145303Z","caller":"traceutil/trace.go:171","msg":"trace[528319496] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"187.584171ms","start":"2026-05-29T01:59:56.957518Z","end":"2026-05-29T01:59:57.145102Z","steps":["trace[528319496] 'process raft request' (duration: 102.333519ms)","trace[528319496] 'compare' (duration: 84.94765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:57.392365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.739237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T01:59:57.392446Z","caller":"traceutil/trace.go:171","msg":"trace[1374371542] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:135; }","duration":"196.869603ms","start":"2026-05-29T01:59:57.195563Z","end":"2026-05-29T01:59:57.392433Z","steps":["trace[1374371542] 'read index received' (duration: 71.011201ms)","trace[1374371542] 'applied index is now lower than readState.Index' (duration: 125.857712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:57.392512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.956997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T01:59:57.392536Z","caller":"traceutil/trace.go:171","msg":"trace[1392474883] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:0; response_revision:131; }","duration":"197.04006ms","start":"2026-05-29T01:59:57.195489Z","end":"2026-05-29T01:59:57.392529Z","steps":["trace[1392474883] 'agreement among raft nodes before linearized reading' (duration: 196.977007ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:57.392522Z","caller":"traceutil/trace.go:171","msg":"trace[1380411216] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"235.587817ms","start":"2026-05-29T01:59:57.156891Z","end":"2026-05-29T01:59:57.392479Z","steps":["trace[1380411216] 'process raft request' (duration: 109.684703ms)","trace[1380411216] 'compare' (duration: 125.618373ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:57.552235Z","caller":"traceutil/trace.go:171","msg":"trace[1823923583] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"145.665859ms","start":"2026-05-29T01:59:57.406532Z","end":"2026-05-29T01:59:57.552198Z","steps":["trace[1823923583] 'process raft request' (duration: 86.455371ms)","trace[1823923583] 'compare' (duration: 58.994218ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:57.902414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.447437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T01:59:57.902605Z","caller":"traceutil/trace.go:171","msg":"trace[2050095336] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"305.689873ms","start":"2026-05-29T01:59:57.596898Z","end":"2026-05-29T01:59:57.902588Z","steps":["trace[2050095336] 'process raft request' (duration: 177.017077ms)","trace[2050095336] 'compare' (duration: 128.266479ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:57.902652Z","caller":"traceutil/trace.go:171","msg":"trace[239994650] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"305.328167ms","start":"2026-05-29T01:59:57.597291Z","end":"2026-05-29T01:59:57.902619Z","steps":["trace[239994650] 'process raft request' (duration: 305.240124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T01:59:57.902715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.806373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T01:59:57.902688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T01:59:57.596884Z","time spent":"305.769095ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T01:59:57.90277Z","caller":"traceutil/trace.go:171","msg":"trace[1209005467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:141; }","duration":"277.961959ms","start":"2026-05-29T01:59:57.624785Z","end":"2026-05-29T01:59:57.902747Z","steps":["trace[1209005467] 'agreement among raft nodes before linearized reading' (duration: 277.852435ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T01:59:57.902601Z","caller":"traceutil/trace.go:171","msg":"trace[956688306] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:144; }","duration":"277.655817ms","start":"2026-05-29T01:59:57.624924Z","end":"2026-05-29T01:59:57.90258Z","steps":["trace[956688306] 'read index received' (duration: 149.016363ms)","trace[956688306] 'applied index is now lower than readState.Index' (duration: 128.638194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:57.902814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T01:59:57.597269Z","time spent":"305.465792ms","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T01:59:58.078936Z","caller":"traceutil/trace.go:171","msg":"trace[1528064459] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"125.383661ms","start":"2026-05-29T01:59:57.953528Z","end":"2026-05-29T01:59:58.078912Z","steps":["trace[1528064459] 'read index received' (duration: 112.426166ms)","trace[1528064459] 'applied index is now lower than readState.Index' (duration: 12.955895ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T01:59:58.079023Z","caller":"traceutil/trace.go:171","msg":"trace[899115152] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"167.406618ms","start":"2026-05-29T01:59:57.911555Z","end":"2026-05-29T01:59:58.078962Z","steps":["trace[899115152] 'process raft request' (duration: 154.352759ms)","trace[899115152] 'compare' (duration: 12.879143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T01:59:58.07929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.747316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T01:59:58.079371Z","caller":"traceutil/trace.go:171","msg":"trace[617014998] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:0; response_revision:144; }","duration":"125.88293ms","start":"2026-05-29T01:59:57.953466Z","end":"2026-05-29T01:59:58.079349Z","steps":["trace[617014998] 'agreement among raft nodes before linearized reading' (duration: 125.61664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:00.065576Z","caller":"traceutil/trace.go:171","msg":"trace[1332871114] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"185.527422ms","start":"2026-05-29T01:59:59.879973Z","end":"2026-05-29T02:00:00.065501Z","steps":["trace[1332871114] 'process raft request' (duration: 108.497062ms)","trace[1332871114] 'compare' (duration: 76.75172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:01.283007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666007634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:01.784176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666007634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:01.792899Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092992453s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T02:00:01.856645Z","caller":"traceutil/trace.go:171","msg":"trace[637225072] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"1.156823428s","start":"2026-05-29T02:00:00.699793Z","end":"2026-05-29T02:00:01.856616Z","steps":["trace[637225072] 'process raft request' (duration: 1.093572256s)","trace[637225072] 'compare' (duration: 63.125838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:01.856784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:00.699763Z","time spent":"1.156957613s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:02.904661Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111527734s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T02:00:02.905122Z","caller":"traceutil/trace.go:171","msg":"trace[125342322] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:264; }","duration":"2.122933185s","start":"2026-05-29T02:00:00.782163Z","end":"2026-05-29T02:00:02.905096Z","steps":["trace[125342322] 'read index received' (duration: 1.011172683s)","trace[125342322] 'applied index is now lower than readState.Index' (duration: 1.111759162s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:02.905473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.12328531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5895"} {"level":"info","ts":"2026-05-29T02:00:02.905168Z","caller":"traceutil/trace.go:171","msg":"trace[1305677513] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"2.203053709s","start":"2026-05-29T02:00:00.702071Z","end":"2026-05-29T02:00:02.905125Z","steps":["trace[1305677513] 'process raft request' (duration: 2.202825111s)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:02.905542Z","caller":"traceutil/trace.go:171","msg":"trace[1826683293] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:261; }","duration":"2.123400853s","start":"2026-05-29T02:00:00.782117Z","end":"2026-05-29T02:00:02.905518Z","steps":["trace[1826683293] 'agreement among raft nodes before linearized reading' (duration: 2.123194846s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:02.905584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:00.782095Z","time spent":"2.123479646s","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5917,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-05-29T02:00:02.905246Z","caller":"traceutil/trace.go:171","msg":"trace[1736664699] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.896389914s","start":"2026-05-29T02:00:01.008806Z","end":"2026-05-29T02:00:02.905196Z","steps":["trace[1736664699] 'process raft request' (duration: 1.896246007s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:02.905776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:01.008787Z","time spent":"1.896913483s","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:02.905638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:00.702051Z","time spent":"2.203497846s","remote":"127.0.0.1:46930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:03.406345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666007640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:03.860867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000275376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T02:00:03.860956Z","caller":"traceutil/trace.go:171","msg":"trace[675736683] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000433322s","start":"2026-05-29T02:00:01.860506Z","end":"2026-05-29T02:00:03.860939Z","steps":["trace[675736683] 'agreement among raft nodes before linearized reading' (duration: 2.000320828s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.861045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:01.860476Z","time spent":"2.000560887s","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 02:00:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-29T02:00:03.90077Z","caller":"traceutil/trace.go:171","msg":"trace[959473167] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"2.040083844s","start":"2026-05-29T02:00:01.860657Z","end":"2026-05-29T02:00:03.900741Z","steps":["trace[959473167] 'process raft request' (duration: 2.039840484s)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:03.900772Z","caller":"traceutil/trace.go:171","msg":"trace[1037101460] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"1.868159005s","start":"2026-05-29T02:00:02.03259Z","end":"2026-05-29T02:00:03.900749Z","steps":["trace[1037101460] 'process raft request' (duration: 1.868084501s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.900961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:01.860642Z","time spent":"2.04024979s","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:03.901345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:02.032574Z","time spent":"1.868658674s","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:03.906794Z","caller":"traceutil/trace.go:171","msg":"trace[1691216802] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"982.58956ms","start":"2026-05-29T02:00:02.924166Z","end":"2026-05-29T02:00:03.906756Z","steps":["trace[1691216802] 'process raft request' (duration: 982.501117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.906845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.046325999s","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-05-29T02:00:03.906901Z","caller":"traceutil/trace.go:171","msg":"trace[2082224747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:265; }","duration":"2.046415452s","start":"2026-05-29T02:00:01.860469Z","end":"2026-05-29T02:00:03.906885Z","steps":["trace[2082224747] 'agreement among raft nodes before linearized reading' (duration: 2.046302248s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.90695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:01.860451Z","time spent":"2.046489795s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T02:00:03.907072Z","caller":"traceutil/trace.go:171","msg":"trace[548911081] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"997.606292ms","start":"2026-05-29T02:00:02.909225Z","end":"2026-05-29T02:00:03.906831Z","steps":["trace[548911081] 'process raft request' (duration: 997.376904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.907172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:02.92415Z","time spent":"982.725906ms","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:03.906818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687880994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-29T02:00:03.907191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:02.909205Z","time spent":"997.946846ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:03.90724Z","caller":"traceutil/trace.go:171","msg":"trace[357166445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:265; }","duration":"1.688374553s","start":"2026-05-29T02:00:02.218854Z","end":"2026-05-29T02:00:03.907228Z","steps":["trace[357166445] 'agreement among raft nodes before linearized reading' (duration: 1.687895644s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.907407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.980867656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T02:00:03.907441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:02.218827Z","time spent":"1.688598131s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T02:00:03.907465Z","caller":"traceutil/trace.go:171","msg":"trace[229111899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:265; }","duration":"2.980965689s","start":"2026-05-29T02:00:00.926486Z","end":"2026-05-29T02:00:03.907452Z","steps":["trace[229111899] 'agreement among raft nodes before linearized reading' (duration: 2.980876886s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:03.907746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:00.926465Z","time spent":"2.981268661s","remote":"127.0.0.1:47128","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":"info","ts":"2026-05-29T02:00:03.906724Z","caller":"traceutil/trace.go:171","msg":"trace[1720010725] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:267; }","duration":"1.00121461s","start":"2026-05-29T02:00:02.905494Z","end":"2026-05-29T02:00:03.906709Z","steps":["trace[1720010725] 'read index received' (duration: 995.076329ms)","trace[1720010725] 'applied index is now lower than readState.Index' (duration: 6.137401ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:04.784617Z","caller":"traceutil/trace.go:171","msg":"trace[217138522] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"245.134611ms","start":"2026-05-29T02:00:04.53945Z","end":"2026-05-29T02:00:04.784585Z","steps":["trace[217138522] 'process raft request' (duration: 245.054648ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:04.784641Z","caller":"traceutil/trace.go:171","msg":"trace[1433707898] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"245.205623ms","start":"2026-05-29T02:00:04.539389Z","end":"2026-05-29T02:00:04.784594Z","steps":["trace[1433707898] 'process raft request' (duration: 241.739304ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:08.788582Z","caller":"traceutil/trace.go:171","msg":"trace[579482278] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"211.486107ms","start":"2026-05-29T02:00:08.577076Z","end":"2026-05-29T02:00:08.788562Z","steps":["trace[579482278] 'process raft request' (duration: 211.325882ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:35.112166Z","caller":"traceutil/trace.go:171","msg":"trace[218413872] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"312.049228ms","start":"2026-05-29T02:00:34.800071Z","end":"2026-05-29T02:00:35.112121Z","steps":["trace[218413872] 'process raft request' (duration: 249.141368ms)","trace[218413872] 'compare' (duration: 62.602321ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:35.112276Z","caller":"traceutil/trace.go:171","msg":"trace[2068854873] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"149.883904ms","start":"2026-05-29T02:00:34.962347Z","end":"2026-05-29T02:00:35.112231Z","steps":["trace[2068854873] 'read index received' (duration: 86.947703ms)","trace[2068854873] 'applied index is now lower than readState.Index' (duration: 62.931691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:35.112427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:34.800046Z","time spent":"312.292665ms","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:35.112481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.112741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T02:00:35.112561Z","caller":"traceutil/trace.go:171","msg":"trace[1124036606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"150.236374ms","start":"2026-05-29T02:00:34.962307Z","end":"2026-05-29T02:00:35.112544Z","steps":["trace[1124036606] 'agreement among raft nodes before linearized reading' (duration: 150.054538ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:35.113017Z","caller":"traceutil/trace.go:171","msg":"trace[630849909] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"141.930883ms","start":"2026-05-29T02:00:34.971067Z","end":"2026-05-29T02:00:35.112998Z","steps":["trace[630849909] 'process raft request' (duration: 141.052769ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:35.113272Z","caller":"traceutil/trace.go:171","msg":"trace[1037090058] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"312.049688ms","start":"2026-05-29T02:00:34.800071Z","end":"2026-05-29T02:00:35.112121Z","steps":["trace[1037090058] 'process raft request' (duration: 311.982976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:35.113487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:34.800047Z","time spent":"313.351984ms","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:37.471217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.059389ms","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-05-29T02:00:37.471331Z","caller":"traceutil/trace.go:171","msg":"trace[1259122524] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"504.193993ms","start":"2026-05-29T02:00:36.967124Z","end":"2026-05-29T02:00:37.471318Z","steps":["trace[1259122524] 'range keys from in-memory index tree' (duration: 504.044789ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:37.471582Z","caller":"traceutil/trace.go:171","msg":"trace[1772897584] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"564.129546ms","start":"2026-05-29T02:00:36.907423Z","end":"2026-05-29T02:00:37.471553Z","steps":["trace[1772897584] 'process raft request' (duration: 543.715421ms)","trace[1772897584] 'compare' (duration: 20.110837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:37.471828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:36.907404Z","time spent":"564.2768ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:37.471942Z","caller":"traceutil/trace.go:171","msg":"trace[1385922262] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"559.659394ms","start":"2026-05-29T02:00:36.912241Z","end":"2026-05-29T02:00:37.4719Z","steps":["trace[1385922262] 'process raft request' (duration: 559.422917ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:37.472083Z","caller":"traceutil/trace.go:171","msg":"trace[1013050513] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:601; }","duration":"349.484599ms","start":"2026-05-29T02:00:37.122581Z","end":"2026-05-29T02:00:37.472066Z","steps":["trace[1013050513] 'read index received' (duration: 328.644582ms)","trace[1013050513] 'applied index is now lower than readState.Index' (duration: 20.838197ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:37.472098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:36.9122Z","time spent":"559.816138ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:37.472159Z","caller":"traceutil/trace.go:171","msg":"trace[1564223785] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"548.837366ms","start":"2026-05-29T02:00:36.923272Z","end":"2026-05-29T02:00:37.47211Z","steps":["trace[1564223785] 'process raft request' (duration: 548.565358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:37.472242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.654133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T02:00:37.4723Z","caller":"traceutil/trace.go:171","msg":"trace[1641046068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"349.745566ms","start":"2026-05-29T02:00:37.122543Z","end":"2026-05-29T02:00:37.472288Z","steps":["trace[1641046068] 'agreement among raft nodes before linearized reading' (duration: 349.578961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:37.472332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:37.122525Z","time spent":"349.799697ms","remote":"127.0.0.1:47128","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":"warn","ts":"2026-05-29T02:00:37.472364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:36.923251Z","time spent":"549.020701ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:40.516388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.470672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:40.51648Z","caller":"traceutil/trace.go:171","msg":"trace[170643839] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:626; }","duration":"100.595795ms","start":"2026-05-29T02:00:40.415863Z","end":"2026-05-29T02:00:40.516459Z","steps":["trace[170643839] 'range keys from in-memory index tree' (duration: 100.37567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:40.516584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.642176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:40.516673Z","caller":"traceutil/trace.go:171","msg":"trace[1941861862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"100.74692ms","start":"2026-05-29T02:00:40.415907Z","end":"2026-05-29T02:00:40.516654Z","steps":["trace[1941861862] 'count revisions from in-memory index tree' (duration: 100.570005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:40.516817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.908454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:40.51686Z","caller":"traceutil/trace.go:171","msg":"trace[961633833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"100.972275ms","start":"2026-05-29T02:00:40.415876Z","end":"2026-05-29T02:00:40.516848Z","steps":["trace[961633833] 'range keys from in-memory index tree' (duration: 100.842312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:41.212113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.01575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:41.212303Z","caller":"traceutil/trace.go:171","msg":"trace[999726649] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"203.710713ms","start":"2026-05-29T02:00:41.008532Z","end":"2026-05-29T02:00:41.212243Z","steps":["trace[999726649] 'process raft request' (duration: 15.18696ms)","trace[999726649] 'compare' (duration: 186.958533ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:41.778277Z","caller":"traceutil/trace.go:171","msg":"trace[1913365633] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"145.747181ms","start":"2026-05-29T02:00:41.6325Z","end":"2026-05-29T02:00:41.778247Z","steps":["trace[1913365633] 'process raft request' (duration: 145.331099ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:41.957575Z","caller":"traceutil/trace.go:171","msg":"trace[1244009747] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"121.153833ms","start":"2026-05-29T02:00:41.836392Z","end":"2026-05-29T02:00:41.957545Z","steps":["trace[1244009747] 'process raft request' (duration: 77.313401ms)","trace[1244009747] 'compare' (duration: 43.697569ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:42.360947Z","caller":"traceutil/trace.go:171","msg":"trace[900022583] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"131.96403ms","start":"2026-05-29T02:00:42.228963Z","end":"2026-05-29T02:00:42.360927Z","steps":["trace[900022583] 'process raft request' (duration: 76.800362ms)","trace[900022583] 'compare' (duration: 55.071955ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:42.638157Z","caller":"traceutil/trace.go:171","msg":"trace[1653453383] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"127.262517ms","start":"2026-05-29T02:00:42.510861Z","end":"2026-05-29T02:00:42.638124Z","steps":["trace[1653453383] 'process raft request' (duration: 126.975499ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:43.738827Z","caller":"traceutil/trace.go:171","msg":"trace[1477429859] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"107.908049ms","start":"2026-05-29T02:00:43.630891Z","end":"2026-05-29T02:00:43.738799Z","steps":["trace[1477429859] 'process raft request' (duration: 84.033742ms)","trace[1477429859] 'compare' (duration: 23.390284ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:44.917161Z","caller":"traceutil/trace.go:171","msg":"trace[2021566217] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"210.140777ms","start":"2026-05-29T02:00:44.707001Z","end":"2026-05-29T02:00:44.917141Z","steps":["trace[2021566217] 'process raft request' (duration: 209.753538ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:45.36851Z","caller":"traceutil/trace.go:171","msg":"trace[1863124959] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"385.086187ms","start":"2026-05-29T02:00:44.983361Z","end":"2026-05-29T02:00:45.368448Z","steps":["trace[1863124959] 'process raft request' (duration: 382.663844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:45.368701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:44.983336Z","time spent":"385.297322ms","remote":"127.0.0.1:47092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:45.556131Z","caller":"traceutil/trace.go:171","msg":"trace[574946526] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"183.317662ms","start":"2026-05-29T02:00:45.372792Z","end":"2026-05-29T02:00:45.55611Z","steps":["trace[574946526] 'read index received' (duration: 99.417978ms)","trace[574946526] 'applied index is now lower than readState.Index' (duration: 83.898654ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:45.556193Z","caller":"traceutil/trace.go:171","msg":"trace[738458885] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"390.698435ms","start":"2026-05-29T02:00:45.165472Z","end":"2026-05-29T02:00:45.556171Z","steps":["trace[738458885] 'process raft request' (duration: 306.805192ms)","trace[738458885] 'compare' (duration: 83.694998ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:45.556829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:45.165446Z","time spent":"390.795528ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:45.557055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.241155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T02:00:45.557145Z","caller":"traceutil/trace.go:171","msg":"trace[335770480] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:652; }","duration":"184.355708ms","start":"2026-05-29T02:00:45.372765Z","end":"2026-05-29T02:00:45.55712Z","steps":["trace[335770480] 'agreement among raft nodes before linearized reading' (duration: 184.192784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:46.077918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666008502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:46.578857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666008502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:47.079788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360946929666008502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:00:47.37408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.656904ms","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-05-29T02:00:47.374195Z","caller":"traceutil/trace.go:171","msg":"trace[526471954] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:653; }","duration":"406.783017ms","start":"2026-05-29T02:00:46.967395Z","end":"2026-05-29T02:00:47.374178Z","steps":["trace[526471954] 'range keys from in-memory index tree' (duration: 406.642873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.375288Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.804721619s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T02:00:47.375553Z","caller":"traceutil/trace.go:171","msg":"trace[827799867] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.805121521s","start":"2026-05-29T02:00:45.570416Z","end":"2026-05-29T02:00:47.375538Z","steps":["trace[827799867] 'process raft request' (duration: 1.805010178s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.375677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:45.570397Z","time spent":"1.805223853s","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:47.379282Z","caller":"traceutil/trace.go:171","msg":"trace[1514416743] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"1.8020179s","start":"2026-05-29T02:00:45.577245Z","end":"2026-05-29T02:00:47.379263Z","steps":["trace[1514416743] 'read index received' (duration: 1.798487851s)","trace[1514416743] 'applied index is now lower than readState.Index' (duration: 3.529239ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:47.379354Z","caller":"traceutil/trace.go:171","msg":"trace[663250344] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.679055672s","start":"2026-05-29T02:00:45.700292Z","end":"2026-05-29T02:00:47.379347Z","steps":["trace[663250344] 'process raft request' (duration: 1.678882348s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.379438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:45.700266Z","time spent":"1.679106093s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:47.379497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144082125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T02:00:47.379493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152535848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-29T02:00:47.379498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802226354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:47.379543Z","caller":"traceutil/trace.go:171","msg":"trace[614535531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.15262497s","start":"2026-05-29T02:00:46.226905Z","end":"2026-05-29T02:00:47.37953Z","steps":["trace[614535531] 'agreement among raft nodes before linearized reading' (duration: 1.152480326s)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:47.379556Z","caller":"traceutil/trace.go:171","msg":"trace[662181675] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:655; }","duration":"1.802323157s","start":"2026-05-29T02:00:45.577217Z","end":"2026-05-29T02:00:47.379541Z","steps":["trace[662181675] 'agreement among raft nodes before linearized reading' (duration: 1.802227075s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.379558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007236432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T02:00:47.379587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:45.577205Z","time spent":"1.802375238s","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-29T02:00:47.379522Z","caller":"traceutil/trace.go:171","msg":"trace[822696027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"1.144126436s","start":"2026-05-29T02:00:46.235389Z","end":"2026-05-29T02:00:47.379515Z","steps":["trace[822696027] 'agreement among raft nodes before linearized reading' (duration: 1.144029723s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.379582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:46.22689Z","time spent":"1.152684501s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T02:00:47.379651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:46.23538Z","time spent":"1.14426421s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T02:00:47.379649Z","caller":"traceutil/trace.go:171","msg":"trace[504670208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.007329505s","start":"2026-05-29T02:00:46.372304Z","end":"2026-05-29T02:00:47.379633Z","steps":["trace[504670208] 'agreement among raft nodes before linearized reading' (duration: 1.007111559s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:47.379685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:46.372251Z","time spent":"1.007426317s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T02:00:55.613878Z","caller":"traceutil/trace.go:171","msg":"trace[892206334] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"153.047481ms","start":"2026-05-29T02:00:55.460812Z","end":"2026-05-29T02:00:55.613859Z","steps":["trace[892206334] 'process raft request' (duration: 87.147181ms)","trace[892206334] 'compare' (duration: 65.526622ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:56.431883Z","caller":"traceutil/trace.go:171","msg":"trace[2103617984] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"150.311502ms","start":"2026-05-29T02:00:56.28155Z","end":"2026-05-29T02:00:56.431862Z","steps":["trace[2103617984] 'process raft request' (duration: 127.352705ms)","trace[2103617984] 'compare' (duration: 22.755081ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:56.451928Z","caller":"traceutil/trace.go:171","msg":"trace[1606246927] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"109.680482ms","start":"2026-05-29T02:00:56.342221Z","end":"2026-05-29T02:00:56.451901Z","steps":["trace[1606246927] 'process raft request' (duration: 109.58378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:03.06569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.826246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4z22m\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-29T02:01:03.065747Z","caller":"traceutil/trace.go:171","msg":"trace[1900619071] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4z22m; range_end:; response_count:1; response_revision:813; }","duration":"505.939359ms","start":"2026-05-29T02:01:02.559794Z","end":"2026-05-29T02:01:03.065734Z","steps":["trace[1900619071] 'range keys from in-memory index tree' (duration: 505.656083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:03.065773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.559774Z","time spent":"505.993159ms","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4z22m\" "} {"level":"warn","ts":"2026-05-29T02:01:03.065859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.792419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:03.065913Z","caller":"traceutil/trace.go:171","msg":"trace[104025361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"519.884922ms","start":"2026-05-29T02:01:02.54601Z","end":"2026-05-29T02:01:03.065895Z","steps":["trace[104025361] 'range keys from in-memory index tree' (duration: 519.708018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:03.065937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.545992Z","time spent":"519.938713ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T02:01:03.249508Z","caller":"traceutil/trace.go:171","msg":"trace[885082135] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:846; }","duration":"112.360206ms","start":"2026-05-29T02:01:03.137126Z","end":"2026-05-29T02:01:03.249487Z","steps":["trace[885082135] 'read index received' (duration: 23.783718ms)","trace[885082135] 'applied index is now lower than readState.Index' (duration: 88.575548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:03.249634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.487429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:01:03.249615Z","caller":"traceutil/trace.go:171","msg":"trace[216005360] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"174.22164ms","start":"2026-05-29T02:01:03.075362Z","end":"2026-05-29T02:01:03.249584Z","steps":["trace[216005360] 'process raft request' (duration: 173.945434ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:03.249665Z","caller":"traceutil/trace.go:171","msg":"trace[1227716097] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:815; }","duration":"112.567601ms","start":"2026-05-29T02:01:03.137089Z","end":"2026-05-29T02:01:03.249656Z","steps":["trace[1227716097] 'agreement among raft nodes before linearized reading' (duration: 112.480039ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:17.350214Z","caller":"traceutil/trace.go:171","msg":"trace[786382689] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"128.26408ms","start":"2026-05-29T02:01:17.221914Z","end":"2026-05-29T02:01:17.350178Z","steps":["trace[786382689] 'read index received' (duration: 128.042246ms)","trace[786382689] 'applied index is now lower than readState.Index' (duration: 220.914µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:17.350534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.597226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T02:01:17.350528Z","caller":"traceutil/trace.go:171","msg":"trace[1102006907] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"265.351619ms","start":"2026-05-29T02:01:17.084937Z","end":"2026-05-29T02:01:17.350289Z","steps":["trace[1102006907] 'process raft request' (duration: 265.051314ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:17.350633Z","caller":"traceutil/trace.go:171","msg":"trace[112367170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"128.675349ms","start":"2026-05-29T02:01:17.221904Z","end":"2026-05-29T02:01:17.35058Z","steps":["trace[112367170] 'agreement among raft nodes before linearized reading' (duration: 128.397562ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:21.826569Z","caller":"traceutil/trace.go:171","msg":"trace[1426803084] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"117.337052ms","start":"2026-05-29T02:01:21.709209Z","end":"2026-05-29T02:01:21.826547Z","steps":["trace[1426803084] 'process raft request' (duration: 117.22348ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:22.039876Z","caller":"traceutil/trace.go:171","msg":"trace[11590825] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"209.305579ms","start":"2026-05-29T02:01:21.830537Z","end":"2026-05-29T02:01:22.039842Z","steps":["trace[11590825] 'process raft request' (duration: 182.850378ms)","trace[11590825] 'compare' (duration: 26.182445ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:22.044948Z","caller":"traceutil/trace.go:171","msg":"trace[85564867] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"210.761807ms","start":"2026-05-29T02:01:21.834168Z","end":"2026-05-29T02:01:22.044929Z","steps":["trace[85564867] 'process raft request' (duration: 210.659385ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:35.624192Z","caller":"traceutil/trace.go:171","msg":"trace[575136229] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"131.833236ms","start":"2026-05-29T02:01:35.492321Z","end":"2026-05-29T02:01:35.624154Z","steps":["trace[575136229] 'process raft request' (duration: 131.614883ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.361283Z","caller":"traceutil/trace.go:171","msg":"trace[381941654] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"217.607479ms","start":"2026-05-29T02:01:36.14365Z","end":"2026-05-29T02:01:36.361257Z","steps":["trace[381941654] 'read index received' (duration: 217.391056ms)","trace[381941654] 'applied index is now lower than readState.Index' (duration: 215.373µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:36.36145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.784372ms","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-05-29T02:01:36.361491Z","caller":"traceutil/trace.go:171","msg":"trace[1396523618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"217.866954ms","start":"2026-05-29T02:01:36.143616Z","end":"2026-05-29T02:01:36.361483Z","steps":["trace[1396523618] 'agreement among raft nodes before linearized reading' (duration: 217.738591ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.3613Z","caller":"traceutil/trace.go:171","msg":"trace[434359283] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"324.707672ms","start":"2026-05-29T02:01:36.036568Z","end":"2026-05-29T02:01:36.361276Z","steps":["trace[434359283] 'process raft request' (duration: 324.516639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:36.361743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:36.036556Z","time spent":"325.029079ms","remote":"127.0.0.1:47092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:36.436064Z","caller":"traceutil/trace.go:171","msg":"trace[1020779629] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"277.732086ms","start":"2026-05-29T02:01:36.158316Z","end":"2026-05-29T02:01:36.436048Z","steps":["trace[1020779629] 'process raft request' (duration: 277.568093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:36.436256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.074079ms","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-05-29T02:01:36.436326Z","caller":"traceutil/trace.go:171","msg":"trace[379857618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"257.18767ms","start":"2026-05-29T02:01:36.179121Z","end":"2026-05-29T02:01:36.436309Z","steps":["trace[379857618] 'agreement among raft nodes before linearized reading' (duration: 256.935126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:36.498005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.219407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"warn","ts":"2026-05-29T02:01:36.498032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.049804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4z22m\" ","response":"range_response_count:1 size:4126"} {"level":"info","ts":"2026-05-29T02:01:36.498071Z","caller":"traceutil/trace.go:171","msg":"trace[1670972736] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1057; }","duration":"129.313309ms","start":"2026-05-29T02:01:36.368739Z","end":"2026-05-29T02:01:36.498053Z","steps":["trace[1670972736] 'agreement among raft nodes before linearized reading' (duration: 129.144415ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.498087Z","caller":"traceutil/trace.go:171","msg":"trace[1997922702] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4z22m; range_end:; response_count:1; response_revision:1057; }","duration":"129.126245ms","start":"2026-05-29T02:01:36.368948Z","end":"2026-05-29T02:01:36.498074Z","steps":["trace[1997922702] 'agreement among raft nodes before linearized reading' (duration: 128.950011ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.498678Z","caller":"traceutil/trace.go:171","msg":"trace[1415579344] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"132.491536ms","start":"2026-05-29T02:01:36.36617Z","end":"2026-05-29T02:01:36.498662Z","steps":["trace[1415579344] 'process raft request' (duration: 131.60552ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.691191Z","caller":"traceutil/trace.go:171","msg":"trace[61381281] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"185.246568ms","start":"2026-05-29T02:01:36.505915Z","end":"2026-05-29T02:01:36.691162Z","steps":["trace[61381281] 'process raft request' (duration: 185.099826ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:36.692087Z","caller":"traceutil/trace.go:171","msg":"trace[299665455] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1099; }","duration":"149.286782ms","start":"2026-05-29T02:01:36.54278Z","end":"2026-05-29T02:01:36.692067Z","steps":["trace[299665455] 'read index received' (duration: 149.278042ms)","trace[299665455] 'applied index is now lower than readState.Index' (duration: 7.23µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:36.692203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.396936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:36.692261Z","caller":"traceutil/trace.go:171","msg":"trace[1355816593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"149.500517ms","start":"2026-05-29T02:01:36.542747Z","end":"2026-05-29T02:01:36.692248Z","steps":["trace[1355816593] 'agreement among raft nodes before linearized reading' (duration: 149.382875ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:42.351123Z","caller":"traceutil/trace.go:171","msg":"trace[1602404616] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"190.849861ms","start":"2026-05-29T02:02:42.160241Z","end":"2026-05-29T02:02:42.35109Z","steps":["trace[1602404616] 'process raft request' (duration: 190.698368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:03:06.383537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.21056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"warn","ts":"2026-05-29T02:03:06.383618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.216901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T02:03:06.383678Z","caller":"traceutil/trace.go:171","msg":"trace[765858716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"125.333432ms","start":"2026-05-29T02:03:06.258327Z","end":"2026-05-29T02:03:06.383661Z","steps":["trace[765858716] 'range keys from in-memory index tree' (duration: 125.064717ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:03:06.383681Z","caller":"traceutil/trace.go:171","msg":"trace[1038403882] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1397; }","duration":"125.390202ms","start":"2026-05-29T02:03:06.258241Z","end":"2026-05-29T02:03:06.383632Z","steps":["trace[1038403882] 'range keys from in-memory index tree' (duration: 125.096798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:03:06.383819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.627554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-05-29T02:03:06.383904Z","caller":"traceutil/trace.go:171","msg":"trace[1268261292] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1397; }","duration":"114.746225ms","start":"2026-05-29T02:03:06.269138Z","end":"2026-05-29T02:03:06.383884Z","steps":["trace[1268261292] 'range keys from in-memory index tree' (duration: 114.489863ms)"],"step_count":1}