{"level":"warn","ts":"2026-05-05T10:15:20.657448Z","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-05T10:15:20.657534Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170: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-05T10:15:20.657617Z","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-05T10:15:20.657643Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-05T10:15:20.657687Z","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-05T10:15:20.65831Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-05-05T10:15:20.658465Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170: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-05T10:15:20.66388Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.81371ms"} {"level":"info","ts":"2026-05-05T10:15:20.672749Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-05-05T10:15:20.672831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:15:20.672949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:15:20.672963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:15:20.672992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:15:20.673032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-05-05T10:15:20.68109Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:15:20.682614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:15:20.68494Z","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-05T10:15:20.691089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:15:20.693822Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:15:20.694217Z","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-05T10:15:20.694308Z","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-05T10:15:20.694322Z","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-05T10:15:20.694743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-05-05T10:15:20.695152Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-05T10:15:20.697506Z","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-05T10:15:20.698214Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:15:20.697908Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-05T10:15:20.699465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-05T10:15:20.698431Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:15:20.973444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:15:20.973506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:15:20.973601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-05-05T10:15:20.973653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:15:20.973672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-05T10:15:20.973718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:15:20.973738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-05T10:15:20.975098Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:15:20.975145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:15:20.975303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:15:20.975429Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:20.975557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:15:20.975602Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:15:20.976313Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:20.976411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:20.976444Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:20.978647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T10:15:20.978927Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"info","ts":"2026-05-05T10:15:29.136848Z","caller":"traceutil/trace.go:171","msg":"trace[905833502] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"122.126945ms","start":"2026-05-05T10:15:29.014691Z","end":"2026-05-05T10:15:29.136818Z","steps":["trace[905833502] 'process raft request' (duration: 122.028491ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:30.562608Z","caller":"traceutil/trace.go:171","msg":"trace[959729860] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"280.863719ms","start":"2026-05-05T10:15:30.281721Z","end":"2026-05-05T10:15:30.562585Z","steps":["trace[959729860] 'read index received' (duration: 280.710113ms)","trace[959729860] 'applied index is now lower than readState.Index' (duration: 152.716µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:15:30.562963Z","caller":"traceutil/trace.go:171","msg":"trace[1427448381] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"298.227482ms","start":"2026-05-05T10:15:30.264698Z","end":"2026-05-05T10:15:30.562926Z","steps":["trace[1427448381] 'process raft request' (duration: 297.786654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:30.563028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.269576ms","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-05T10:15:30.563606Z","caller":"traceutil/trace.go:171","msg":"trace[639493050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"281.422001ms","start":"2026-05-05T10:15:30.281678Z","end":"2026-05-05T10:15:30.5631Z","steps":["trace[639493050] 'agreement among raft nodes before linearized reading' (duration: 281.230574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:36.364004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714455730119763,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:15:36.429086Z","caller":"traceutil/trace.go:171","msg":"trace[1810571465] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"619.304317ms","start":"2026-05-05T10:15:35.809755Z","end":"2026-05-05T10:15:36.42906Z","steps":["trace[1810571465] 'process raft request' (duration: 619.136261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:36.429842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:35.809737Z","time spent":"619.530305ms","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:15:37.422026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.827744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:15:37.422124Z","caller":"traceutil/trace.go:171","msg":"trace[716514091] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"1.558838189s","start":"2026-05-05T10:15:35.863269Z","end":"2026-05-05T10:15:37.422108Z","steps":["trace[716514091] 'read index received' (duration: 565.597596ms)","trace[716514091] 'applied index is now lower than readState.Index' (duration: 993.239313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:37.42238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559080098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4420"} {"level":"warn","ts":"2026-05-05T10:15:37.422468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530711098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:15:37.422491Z","caller":"traceutil/trace.go:171","msg":"trace[1042710327] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:371; }","duration":"1.559216623s","start":"2026-05-05T10:15:35.863236Z","end":"2026-05-05T10:15:37.422453Z","steps":["trace[1042710327] 'agreement among raft nodes before linearized reading' (duration: 1.559006585s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:37.422505Z","caller":"traceutil/trace.go:171","msg":"trace[459142623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.53078451s","start":"2026-05-05T10:15:35.89171Z","end":"2026-05-05T10:15:37.422494Z","steps":["trace[459142623] 'agreement among raft nodes before linearized reading' (duration: 1.530707567s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:37.422549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:35.863221Z","time spent":"1.559317346s","remote":"127.0.0.1:54028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4443,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T10:15:37.422589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:35.891689Z","time spent":"1.530886394s","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T10:15:37.422739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.768437ms","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-05T10:15:37.42282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.090894ms","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-05T10:15:37.422859Z","caller":"traceutil/trace.go:171","msg":"trace[347884520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"830.128035ms","start":"2026-05-05T10:15:36.592719Z","end":"2026-05-05T10:15:37.422847Z","steps":["trace[347884520] 'agreement among raft nodes before linearized reading' (duration: 830.064723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:37.422825Z","caller":"traceutil/trace.go:171","msg":"trace[832079023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"826.870341ms","start":"2026-05-05T10:15:36.595936Z","end":"2026-05-05T10:15:37.422806Z","steps":["trace[832079023] 'agreement among raft nodes before linearized reading' (duration: 826.736346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:37.42298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.92754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-05T10:15:37.42287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392749617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:15:37.422308Z","caller":"traceutil/trace.go:171","msg":"trace[960293318] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.462825308s","start":"2026-05-05T10:15:35.959457Z","end":"2026-05-05T10:15:37.422283Z","steps":["trace[960293318] 'process raft request' (duration: 835.495056ms)","trace[960293318] 'compare' (duration: 626.464562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:37.423232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:35.95943Z","time spent":"1.463657727s","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:37.423276Z","caller":"traceutil/trace.go:171","msg":"trace[1805053258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"1.393018596s","start":"2026-05-05T10:15:36.030057Z","end":"2026-05-05T10:15:37.423076Z","steps":["trace[1805053258] 'agreement among raft nodes before linearized reading' (duration: 1.392730166s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:37.423441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:36.030036Z","time spent":"1.393386019s","remote":"127.0.0.1:53872","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-05-05T10:15:37.422984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:36.595926Z","time spent":"827.040886ms","remote":"127.0.0.1:54124","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":"warn","ts":"2026-05-05T10:15:37.422898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:36.592678Z","time spent":"830.209348ms","remote":"127.0.0.1:54124","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-05-05T10:15:37.423044Z","caller":"traceutil/trace.go:171","msg":"trace[632907898] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:371; }","duration":"989.017623ms","start":"2026-05-05T10:15:36.434006Z","end":"2026-05-05T10:15:37.423024Z","steps":["trace[632907898] 'agreement among raft nodes before linearized reading' (duration: 988.91839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:37.423854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:36.433988Z","time spent":"989.848751ms","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-05T10:15:51.299018Z","caller":"traceutil/trace.go:171","msg":"trace[102210558] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"176.320504ms","start":"2026-05-05T10:15:51.122676Z","end":"2026-05-05T10:15:51.298996Z","steps":["trace[102210558] 'process raft request' (duration: 176.186281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307177Z","caller":"traceutil/trace.go:171","msg":"trace[1855224200] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"179.391736ms","start":"2026-05-05T10:15:51.127763Z","end":"2026-05-05T10:15:51.307155Z","steps":["trace[1855224200] 'process raft request' (duration: 178.752139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307349Z","caller":"traceutil/trace.go:171","msg":"trace[768730797] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"167.383844ms","start":"2026-05-05T10:15:51.139945Z","end":"2026-05-05T10:15:51.307329Z","steps":["trace[768730797] 'process raft request' (duration: 167.21879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307507Z","caller":"traceutil/trace.go:171","msg":"trace[2110848248] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"179.644273ms","start":"2026-05-05T10:15:51.127854Z","end":"2026-05-05T10:15:51.307498Z","steps":["trace[2110848248] 'process raft request' (duration: 179.221141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307546Z","caller":"traceutil/trace.go:171","msg":"trace[937966674] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"162.689188ms","start":"2026-05-05T10:15:51.144825Z","end":"2026-05-05T10:15:51.307514Z","steps":["trace[937966674] 'process raft request' (duration: 162.414921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307656Z","caller":"traceutil/trace.go:171","msg":"trace[564028456] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"167.632011ms","start":"2026-05-05T10:15:51.140001Z","end":"2026-05-05T10:15:51.307633Z","steps":["trace[564028456] 'process raft request' (duration: 167.21135ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307715Z","caller":"traceutil/trace.go:171","msg":"trace[1359157319] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"157.090627ms","start":"2026-05-05T10:15:51.150617Z","end":"2026-05-05T10:15:51.307708Z","steps":["trace[1359157319] 'process raft request' (duration: 156.683716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307724Z","caller":"traceutil/trace.go:171","msg":"trace[346888862] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"169.004008ms","start":"2026-05-05T10:15:51.138713Z","end":"2026-05-05T10:15:51.307717Z","steps":["trace[346888862] 'process raft request' (duration: 168.428842ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307788Z","caller":"traceutil/trace.go:171","msg":"trace[995153046] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"138.135749ms","start":"2026-05-05T10:15:51.169646Z","end":"2026-05-05T10:15:51.307781Z","steps":["trace[995153046] 'process raft request' (duration: 137.735049ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.30766Z","caller":"traceutil/trace.go:171","msg":"trace[1405654573] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"158.258569ms","start":"2026-05-05T10:15:51.149383Z","end":"2026-05-05T10:15:51.307642Z","steps":["trace[1405654573] 'process raft request' (duration: 157.882798ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.307694Z","caller":"traceutil/trace.go:171","msg":"trace[1692788370] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"169.034219ms","start":"2026-05-05T10:15:51.138648Z","end":"2026-05-05T10:15:51.307682Z","steps":["trace[1692788370] 'process raft request' (duration: 168.459193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:51.662013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.867563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:15:51.662221Z","caller":"traceutil/trace.go:171","msg":"trace[1518133620] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"261.210573ms","start":"2026-05-05T10:15:51.400994Z","end":"2026-05-05T10:15:51.662205Z","steps":["trace[1518133620] 'process raft request' (duration: 261.129611ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.662305Z","caller":"traceutil/trace.go:171","msg":"trace[897359195] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"261.394778ms","start":"2026-05-05T10:15:51.400878Z","end":"2026-05-05T10:15:51.662273Z","steps":["trace[897359195] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; req_size:7690; } (duration: 254.75158ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.670664Z","caller":"traceutil/trace.go:171","msg":"trace[494240007] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"224.028974ms","start":"2026-05-05T10:15:51.44661Z","end":"2026-05-05T10:15:51.670639Z","steps":["trace[494240007] 'process raft request' (duration: 223.842779ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.675304Z","caller":"traceutil/trace.go:171","msg":"trace[490186418] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"153.508601ms","start":"2026-05-05T10:15:51.521781Z","end":"2026-05-05T10:15:51.675289Z","steps":["trace[490186418] 'read index received' (duration: 148.690132ms)","trace[490186418] 'applied index is now lower than readState.Index' (duration: 4.817929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:51.675511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.727338ms","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-05T10:15:51.675534Z","caller":"traceutil/trace.go:171","msg":"trace[828297523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"153.796789ms","start":"2026-05-05T10:15:51.521731Z","end":"2026-05-05T10:15:51.675528Z","steps":["trace[828297523] 'agreement among raft nodes before linearized reading' (duration: 153.725627ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:51.675911Z","caller":"traceutil/trace.go:171","msg":"trace[1336055112] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"197.929524ms","start":"2026-05-05T10:15:51.477965Z","end":"2026-05-05T10:15:51.675894Z","steps":["trace[1336055112] 'process raft request' (duration: 197.256466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:51.679111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.08635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:15:51.679152Z","caller":"traceutil/trace.go:171","msg":"trace[505355968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"156.158143ms","start":"2026-05-05T10:15:51.522984Z","end":"2026-05-05T10:15:51.679142Z","steps":["trace[505355968] 'agreement among raft nodes before linearized reading' (duration: 156.088901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:51.679319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.016948ms","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-05-05T10:15:51.679343Z","caller":"traceutil/trace.go:171","msg":"trace[670479146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"131.061409ms","start":"2026-05-05T10:15:51.548275Z","end":"2026-05-05T10:15:51.679337Z","steps":["trace[670479146] 'agreement among raft nodes before linearized reading' (duration: 130.971507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:51.680999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.588901ms","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-05T10:15:51.681057Z","caller":"traceutil/trace.go:171","msg":"trace[1693091229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"157.702174ms","start":"2026-05-05T10:15:51.523343Z","end":"2026-05-05T10:15:51.681045Z","steps":["trace[1693091229] 'agreement among raft nodes before linearized reading' (duration: 157.587771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:57.830257Z","caller":"traceutil/trace.go:171","msg":"trace[1134263809] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"112.744288ms","start":"2026-05-05T10:15:57.717484Z","end":"2026-05-05T10:15:57.830228Z","steps":["trace[1134263809] 'read index received' (duration: 111.976449ms)","trace[1134263809] 'applied index is now lower than readState.Index' (duration: 766.449µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:57.830516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.932672ms","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-05T10:15:57.830551Z","caller":"traceutil/trace.go:171","msg":"trace[939209692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"113.001375ms","start":"2026-05-05T10:15:57.717537Z","end":"2026-05-05T10:15:57.830539Z","steps":["trace[939209692] 'agreement among raft nodes before linearized reading' (duration: 112.842191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:57.830578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.825816ms","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-05T10:15:57.830629Z","caller":"traceutil/trace.go:171","msg":"trace[1059679867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"111.895427ms","start":"2026-05-05T10:15:57.718719Z","end":"2026-05-05T10:15:57.830614Z","steps":["trace[1059679867] 'agreement among raft nodes before linearized reading' (duration: 111.748704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:57.830626Z","caller":"traceutil/trace.go:171","msg":"trace[728814399] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"156.761615ms","start":"2026-05-05T10:15:57.673827Z","end":"2026-05-05T10:15:57.830589Z","steps":["trace[728814399] 'process raft request' (duration: 155.75477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:57.830753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.183889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T10:15:57.830849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.104542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:15:57.830923Z","caller":"traceutil/trace.go:171","msg":"trace[980560226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"113.449295ms","start":"2026-05-05T10:15:57.717442Z","end":"2026-05-05T10:15:57.830891Z","steps":["trace[980560226] 'agreement among raft nodes before linearized reading' (duration: 112.943643ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:57.830962Z","caller":"traceutil/trace.go:171","msg":"trace[1518418166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"112.256746ms","start":"2026-05-05T10:15:57.718678Z","end":"2026-05-05T10:15:57.830935Z","steps":["trace[1518418166] 'agreement among raft nodes before linearized reading' (duration: 111.910468ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:58.185957Z","caller":"traceutil/trace.go:171","msg":"trace[1621063697] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"350.15775ms","start":"2026-05-05T10:15:57.835761Z","end":"2026-05-05T10:15:58.185919Z","steps":["trace[1621063697] 'process raft request' (duration: 349.942215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.186169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.835737Z","time spent":"350.333465ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:58.654512Z","caller":"traceutil/trace.go:171","msg":"trace[490756813] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"817.658771ms","start":"2026-05-05T10:15:57.836827Z","end":"2026-05-05T10:15:58.654486Z","steps":["trace[490756813] 'process raft request' (duration: 817.521288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.654637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.836806Z","time spent":"817.764294ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:58.654663Z","caller":"traceutil/trace.go:171","msg":"trace[594478006] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"817.236851ms","start":"2026-05-05T10:15:57.837385Z","end":"2026-05-05T10:15:58.654622Z","steps":["trace[594478006] 'process raft request' (duration: 817.002795ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:58.654684Z","caller":"traceutil/trace.go:171","msg":"trace[10946079] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"770.666681ms","start":"2026-05-05T10:15:57.884002Z","end":"2026-05-05T10:15:58.654669Z","steps":["trace[10946079] 'process raft request' (duration: 770.419965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.654716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.101247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lg45f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T10:15:58.654757Z","caller":"traceutil/trace.go:171","msg":"trace[1637996504] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lg45f; range_end:; response_count:1; response_revision:583; }","duration":"752.21322ms","start":"2026-05-05T10:15:57.902533Z","end":"2026-05-05T10:15:58.654746Z","steps":["trace[1637996504] 'agreement among raft nodes before linearized reading' (duration: 752.026226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.654781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.883956Z","time spent":"770.783265ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:15:58.654793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.902397Z","time spent":"752.385835ms","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-lg45f\" "} {"level":"info","ts":"2026-05-05T10:15:58.654882Z","caller":"traceutil/trace.go:171","msg":"trace[521289612] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"818.330147ms","start":"2026-05-05T10:15:57.836541Z","end":"2026-05-05T10:15:58.654871Z","steps":["trace[521289612] 'process raft request' (duration: 817.666631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.654879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.837375Z","time spent":"817.352284ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:15:58.654919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.836533Z","time spent":"818.366628ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:58.654512Z","caller":"traceutil/trace.go:171","msg":"trace[16130446] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:596; }","duration":"751.904453ms","start":"2026-05-05T10:15:57.902585Z","end":"2026-05-05T10:15:58.65449Z","steps":["trace[16130446] 'read index received' (duration: 283.177422ms)","trace[16130446] 'applied index is now lower than readState.Index' (duration: 468.726151ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:15:58.654981Z","caller":"traceutil/trace.go:171","msg":"trace[1235532667] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"751.900543ms","start":"2026-05-05T10:15:57.903072Z","end":"2026-05-05T10:15:58.654973Z","steps":["trace[1235532667] 'process raft request' (duration: 751.3824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.655026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:57.903047Z","time spent":"751.953564ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:15:58.6551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.584921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:15:58.655123Z","caller":"traceutil/trace.go:171","msg":"trace[369245480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"625.674523ms","start":"2026-05-05T10:15:58.029441Z","end":"2026-05-05T10:15:58.655116Z","steps":["trace[369245480] 'agreement among raft nodes before linearized reading' (duration: 625.636262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:58.65514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.029397Z","time spent":"625.738935ms","remote":"127.0.0.1:53888","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-05-05T10:15:59.231579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714455730120145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:15:59.732239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714455730120145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:15:59.951396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.251415685s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T10:15:59.951843Z","caller":"traceutil/trace.go:171","msg":"trace[1120261499] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.278920988s","start":"2026-05-05T10:15:58.672899Z","end":"2026-05-05T10:15:59.95182Z","steps":["trace[1120261499] 'process raft request' (duration: 1.278755374s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:59.95199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.672874Z","time spent":"1.279037061s","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:16:00.055241Z","caller":"traceutil/trace.go:171","msg":"trace[1834694368] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.350956152s","start":"2026-05-05T10:15:58.704256Z","end":"2026-05-05T10:16:00.055213Z","steps":["trace[1834694368] 'process raft request' (duration: 1.350686546s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:00.055287Z","caller":"traceutil/trace.go:171","msg":"trace[1737693556] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.35002544s","start":"2026-05-05T10:15:58.705228Z","end":"2026-05-05T10:16:00.055253Z","steps":["trace[1737693556] 'process raft request' (duration: 1.349887717s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:00.055384Z","caller":"traceutil/trace.go:171","msg":"trace[1433940117] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.349071967s","start":"2026-05-05T10:15:58.706259Z","end":"2026-05-05T10:16:00.055331Z","steps":["trace[1433940117] 'process raft request' (duration: 1.348959984s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:00.055418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.704226Z","time spent":"1.351093675s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T10:16:00.055434Z","caller":"traceutil/trace.go:171","msg":"trace[629542224] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.349188509s","start":"2026-05-05T10:15:58.706218Z","end":"2026-05-05T10:16:00.055406Z","steps":["trace[629542224] 'process raft request' (duration: 1.348956524s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:00.055549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.706247Z","time spent":"1.34921426s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:16:00.055488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.705215Z","time spent":"1.350181213s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:16:00.055719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.706195Z","time spent":"1.349361784s","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:16:00.063228Z","caller":"traceutil/trace.go:171","msg":"trace[860005304] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:603; }","duration":"1.332633335s","start":"2026-05-05T10:15:58.730568Z","end":"2026-05-05T10:16:00.063202Z","steps":["trace[860005304] 'read index received' (duration: 1.221079574s)","trace[860005304] 'applied index is now lower than readState.Index' (duration: 111.552181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:16:00.063402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332831429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:00.063464Z","caller":"traceutil/trace.go:171","msg":"trace[3573035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.332930492s","start":"2026-05-05T10:15:58.730512Z","end":"2026-05-05T10:16:00.063442Z","steps":["trace[3573035] 'agreement among raft nodes before linearized reading' (duration: 1.332826239s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:00.063499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:58.730489Z","time spent":"1.333002904s","remote":"127.0.0.1:53872","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-05-05T10:16:00.066339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036395149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:00.066446Z","caller":"traceutil/trace.go:171","msg":"trace[433336760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.036520732s","start":"2026-05-05T10:15:59.029895Z","end":"2026-05-05T10:16:00.066415Z","steps":["trace[433336760] 'agreement among raft nodes before linearized reading' (duration: 1.03641948s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:00.066494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:59.02987Z","time spent":"1.036616485s","remote":"127.0.0.1:53888","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-05-05T10:16:01.323896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.362816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:01.323977Z","caller":"traceutil/trace.go:171","msg":"trace[70772305] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:597; }","duration":"237.548279ms","start":"2026-05-05T10:16:01.086411Z","end":"2026-05-05T10:16:01.323959Z","steps":["trace[70772305] 'count revisions from in-memory index tree' (duration: 237.246593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:01.324021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.359865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:01.324089Z","caller":"traceutil/trace.go:171","msg":"trace[234839025] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:597; }","duration":"237.461448ms","start":"2026-05-05T10:16:01.086606Z","end":"2026-05-05T10:16:01.324067Z","steps":["trace[234839025] 'range keys from in-memory index tree' (duration: 237.320955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:01.3243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.842427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:01.324331Z","caller":"traceutil/trace.go:171","msg":"trace[586012056] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:597; }","duration":"237.897788ms","start":"2026-05-05T10:16:01.086424Z","end":"2026-05-05T10:16:01.324322Z","steps":["trace[586012056] 'range keys from in-memory index tree' (duration: 237.763545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:14.353992Z","caller":"traceutil/trace.go:171","msg":"trace[1399522349] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"233.301012ms","start":"2026-05-05T10:16:14.120667Z","end":"2026-05-05T10:16:14.353968Z","steps":["trace[1399522349] 'process raft request' (duration: 233.115118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:33.501308Z","caller":"traceutil/trace.go:171","msg":"trace[1319151431] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"624.856868ms","start":"2026-05-05T10:16:32.876404Z","end":"2026-05-05T10:16:33.501261Z","steps":["trace[1319151431] 'process raft request' (duration: 606.48028ms)","trace[1319151431] 'compare' (duration: 17.660296ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:33.50153Z","caller":"traceutil/trace.go:171","msg":"trace[493734743] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:824; }","duration":"470.958318ms","start":"2026-05-05T10:16:33.030545Z","end":"2026-05-05T10:16:33.501504Z","steps":["trace[493734743] 'read index received' (duration: 452.356347ms)","trace[493734743] 'applied index is now lower than readState.Index' (duration: 18.600731ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:33.501603Z","caller":"traceutil/trace.go:171","msg":"trace[1589632130] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"624.679504ms","start":"2026-05-05T10:16:32.876892Z","end":"2026-05-05T10:16:33.501571Z","steps":["trace[1589632130] 'process raft request' (duration: 624.526241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:33.501645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:32.876391Z","time spent":"625.178083ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:16:33.501697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.147042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:16:33.50175Z","caller":"traceutil/trace.go:171","msg":"trace[1520479788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"471.252973ms","start":"2026-05-05T10:16:33.030486Z","end":"2026-05-05T10:16:33.501739Z","steps":["trace[1520479788] 'agreement among raft nodes before linearized reading' (duration: 471.130391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:33.501752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:32.876877Z","time spent":"624.789616ms","remote":"127.0.0.1:54124","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-05-05T10:16:33.501781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:33.030458Z","time spent":"471.315604ms","remote":"127.0.0.1:53872","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-05-05T10:16:33.501851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.339129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d7r98\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T10:16:33.501934Z","caller":"traceutil/trace.go:171","msg":"trace[1754001774] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d7r98; range_end:; response_count:1; response_revision:797; }","duration":"439.45354ms","start":"2026-05-05T10:16:33.062451Z","end":"2026-05-05T10:16:33.501905Z","steps":["trace[1754001774] 'agreement among raft nodes before linearized reading' (duration: 439.240887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:33.50198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:33.06243Z","time spent":"439.537792ms","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d7r98\" "} {"level":"info","ts":"2026-05-05T10:18:11.815785Z","caller":"traceutil/trace.go:171","msg":"trace[662233419] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"167.39487ms","start":"2026-05-05T10:18:11.648357Z","end":"2026-05-05T10:18:11.815752Z","steps":["trace[662233419] 'process raft request' (duration: 121.598204ms)","trace[662233419] 'compare' (duration: 45.502096ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:11.815852Z","caller":"traceutil/trace.go:171","msg":"trace[978575824] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"136.444305ms","start":"2026-05-05T10:18:11.679388Z","end":"2026-05-05T10:18:11.815832Z","steps":["trace[978575824] 'process raft request' (duration: 136.318361ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:11.815887Z","caller":"traceutil/trace.go:171","msg":"trace[1270235132] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"161.19524ms","start":"2026-05-05T10:18:11.654642Z","end":"2026-05-05T10:18:11.815837Z","steps":["trace[1270235132] 'process raft request' (duration: 160.979063ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:24.194434Z","caller":"traceutil/trace.go:171","msg":"trace[122086117] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"156.967833ms","start":"2026-05-05T10:18:24.037431Z","end":"2026-05-05T10:18:24.194399Z","steps":["trace[122086117] 'process raft request' (duration: 136.557899ms)","trace[122086117] 'compare' (duration: 20.27474ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:24.359064Z","caller":"traceutil/trace.go:171","msg":"trace[1455979057] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1382; }","duration":"159.43675ms","start":"2026-05-05T10:18:24.199589Z","end":"2026-05-05T10:18:24.359025Z","steps":["trace[1455979057] 'read index received' (duration: 50.468337ms)","trace[1455979057] 'applied index is now lower than readState.Index' (duration: 108.966983ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:24.359127Z","caller":"traceutil/trace.go:171","msg":"trace[1047556593] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"159.967857ms","start":"2026-05-05T10:18:24.199138Z","end":"2026-05-05T10:18:24.359106Z","steps":["trace[1047556593] 'process raft request' (duration: 153.983371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:24.359293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.663607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T10:18:24.359342Z","caller":"traceutil/trace.go:171","msg":"trace[430204876] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1330; }","duration":"159.772121ms","start":"2026-05-05T10:18:24.199552Z","end":"2026-05-05T10:18:24.359324Z","steps":["trace[430204876] 'agreement among raft nodes before linearized reading' (duration: 159.642827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:24.366582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.058782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:24.366645Z","caller":"traceutil/trace.go:171","msg":"trace[1011715816] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1331; }","duration":"143.203216ms","start":"2026-05-05T10:18:24.223426Z","end":"2026-05-05T10:18:24.366629Z","steps":["trace[1011715816] 'agreement among raft nodes before linearized reading' (duration: 143.031051ms)"],"step_count":1}