{"level":"warn","ts":"2026-04-09T06:01:47.802758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T06:01:47.802862Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.103: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.103:2380","--initial-cluster=instance=https://199.19.213.103:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.103:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.103:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T06:01:47.802939Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T06:01:47.802954Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-09T06:01:47.802977Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T06:01:47.803621Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"]} {"level":"info","ts":"2026-04-09T06:01:47.803704Z","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.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.103:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T06:01:47.808402Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.48001ms"} {"level":"info","ts":"2026-04-09T06:01:47.814647Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d82047944f922143","cluster-id":"223a09c0da413f53"} {"level":"info","ts":"2026-04-09T06:01:47.814733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T06:01:47.814759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 0"} {"level":"info","ts":"2026-04-09T06:01:47.814773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d82047944f922143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T06:01:47.814784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 1"} {"level":"info","ts":"2026-04-09T06:01:47.814818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"warn","ts":"2026-04-09T06:01:47.821387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T06:01:47.822932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T06:01:47.824075Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-09T06:01:47.826426Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d82047944f922143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T06:01:47.827702Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d82047944f922143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T06:01:47.827864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"info","ts":"2026-04-09T06:01:47.827961Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","added-peer-id":"d82047944f922143","added-peer-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-09T06:01:47.828026Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T06:01:47.828099Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T06:01:47.828113Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T06:01:47.831853Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T06:01:47.83216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d82047944f922143","initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T06:01:47.832122Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-09T06:01:47.832411Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-09T06:01:47.832266Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T06:01:48.017979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T06:01:48.018053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T06:01:48.018079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgPreVoteResp from d82047944f922143 at term 1"} {"level":"info","ts":"2026-04-09T06:01:48.018102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became candidate at term 2"} {"level":"info","ts":"2026-04-09T06:01:48.018114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgVoteResp from d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-09T06:01:48.01813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became leader at term 2"} {"level":"info","ts":"2026-04-09T06:01:48.01815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d82047944f922143 elected leader d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-09T06:01:48.02084Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T06:01:48.022265Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d82047944f922143","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.103:2379]}","request-path":"/0/members/d82047944f922143/attributes","cluster-id":"223a09c0da413f53","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T06:01:48.022299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T06:01:48.022399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T06:01:48.022535Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T06:01:48.022654Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T06:01:48.023541Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T06:01:48.023857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T06:01:48.024008Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T06:01:48.026363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.103:2379"} {"level":"info","ts":"2026-04-09T06:01:48.02644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T06:01:50.039944Z","caller":"traceutil/trace.go:171","msg":"trace[1329047972] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"311.831602ms","start":"2026-04-09T06:01:49.728075Z","end":"2026-04-09T06:01:50.039906Z","steps":["trace[1329047972] 'process raft request' (duration: 311.402151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.041267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.728065Z","time spent":"311.996977ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:50.347556Z","caller":"traceutil/trace.go:171","msg":"trace[1880628737] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"619.206644ms","start":"2026-04-09T06:01:49.728252Z","end":"2026-04-09T06:01:50.347458Z","steps":["trace[1880628737] 'process raft request' (duration: 618.889496ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:50.347713Z","caller":"traceutil/trace.go:171","msg":"trace[1350249144] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"610.207522ms","start":"2026-04-09T06:01:49.737423Z","end":"2026-04-09T06:01:50.347631Z","steps":["trace[1350249144] 'read index received' (duration: 302.054801ms)","trace[1350249144] 'applied index is now lower than readState.Index' (duration: 308.151261ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:50.348823Z","caller":"traceutil/trace.go:171","msg":"trace[8525508] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"585.69675ms","start":"2026-04-09T06:01:49.762995Z","end":"2026-04-09T06:01:50.348691Z","steps":["trace[8525508] 'process raft request' (duration: 584.320446ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:50.348369Z","caller":"traceutil/trace.go:171","msg":"trace[629151905] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"550.098325ms","start":"2026-04-09T06:01:49.797656Z","end":"2026-04-09T06:01:50.347754Z","steps":["trace[629151905] 'process raft request' (duration: 549.881379ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:50.348948Z","caller":"traceutil/trace.go:171","msg":"trace[1115696549] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"584.505321ms","start":"2026-04-09T06:01:49.763282Z","end":"2026-04-09T06:01:50.347788Z","steps":["trace[1115696549] 'process raft request' (duration: 584.119301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.349171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.797612Z","time spent":"551.402677ms","remote":"127.0.0.1:57762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:01:50.349169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.762964Z","time spent":"586.040399ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:01:50.349291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.763264Z","time spent":"585.887524ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:01:50.347833Z","caller":"traceutil/trace.go:171","msg":"trace[1407050207] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"584.422319ms","start":"2026-04-09T06:01:49.7634Z","end":"2026-04-09T06:01:50.347822Z","steps":["trace[1407050207] 'process raft request' (duration: 584.033388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.349664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.763391Z","time spent":"586.13716ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:01:50.347747Z","caller":"traceutil/trace.go:171","msg":"trace[285901645] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"583.839165ms","start":"2026-04-09T06:01:49.763868Z","end":"2026-04-09T06:01:50.347708Z","steps":["trace[285901645] 'process raft request' (duration: 583.629359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.350001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.763859Z","time spent":"586.09197ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:01:50.35013Z","caller":"traceutil/trace.go:171","msg":"trace[1546144644] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"584.660685ms","start":"2026-04-09T06:01:49.763156Z","end":"2026-04-09T06:01:50.347817Z","steps":["trace[1546144644] 'process raft request' (duration: 584.208303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.350185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.763149Z","time spent":"587.002621ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:01:50.350523Z","caller":"traceutil/trace.go:171","msg":"trace[1122991276] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"584.293556ms","start":"2026-04-09T06:01:49.76354Z","end":"2026-04-09T06:01:50.347833Z","steps":["trace[1122991276] 'process raft request' (duration: 583.920886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.350616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.763531Z","time spent":"587.055174ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:01:50.350825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.35705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.350914Z","caller":"traceutil/trace.go:171","msg":"trace[376960670] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"613.473093ms","start":"2026-04-09T06:01:49.737415Z","end":"2026-04-09T06:01:50.350888Z","steps":["trace[376960670] 'agreement among raft nodes before linearized reading' (duration: 612.763965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.350966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.737374Z","time spent":"613.582626ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-09T06:01:50.350985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.728228Z","time spent":"619.476402ms","remote":"127.0.0.1:57666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:50.351498Z","caller":"traceutil/trace.go:171","msg":"trace[792644707] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"541.834832ms","start":"2026-04-09T06:01:49.809607Z","end":"2026-04-09T06:01:50.351442Z","steps":["trace[792644707] 'process raft request' (duration: 537.970776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.35159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.809574Z","time spent":"541.955184ms","remote":"127.0.0.1:57856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:01:50.352605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.12733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.352679Z","caller":"traceutil/trace.go:171","msg":"trace[1642986074] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"575.243543ms","start":"2026-04-09T06:01:49.777403Z","end":"2026-04-09T06:01:50.352647Z","steps":["trace[1642986074] 'agreement among raft nodes before linearized reading' (duration: 575.150171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.352719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.777383Z","time spent":"575.326835ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T06:01:50.352936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.619906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.353022Z","caller":"traceutil/trace.go:171","msg":"trace[148216113] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"539.811881ms","start":"2026-04-09T06:01:49.813192Z","end":"2026-04-09T06:01:50.353004Z","steps":["trace[148216113] 'agreement among raft nodes before linearized reading' (duration: 539.588735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.353069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.81318Z","time spent":"539.879512ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T06:01:50.353436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.199646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.353684Z","caller":"traceutil/trace.go:171","msg":"trace[1411660101] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"567.263607ms","start":"2026-04-09T06:01:49.786205Z","end":"2026-04-09T06:01:50.353468Z","steps":["trace[1411660101] 'agreement among raft nodes before linearized reading' (duration: 567.00334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.353727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.786194Z","time spent":"567.522993ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T06:01:50.353966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.927183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.354005Z","caller":"traceutil/trace.go:171","msg":"trace[249734098] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"577.755265ms","start":"2026-04-09T06:01:49.776234Z","end":"2026-04-09T06:01:50.353989Z","steps":["trace[249734098] 'agreement among raft nodes before linearized reading' (duration: 576.889643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.354041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.776226Z","time spent":"577.792645ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T06:01:50.354218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.551023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.354239Z","caller":"traceutil/trace.go:171","msg":"trace[13054333] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:0; response_revision:11; }","duration":"593.606455ms","start":"2026-04-09T06:01:49.760627Z","end":"2026-04-09T06:01:50.354234Z","steps":["trace[13054333] 'agreement among raft nodes before linearized reading' (duration: 591.474452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.354259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.760603Z","time spent":"593.651546ms","remote":"127.0.0.1:57626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.103\" "} {"level":"warn","ts":"2026-04-09T06:01:50.35574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.711997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T06:01:50.353948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.552525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T06:01:50.356042Z","caller":"traceutil/trace.go:171","msg":"trace[105099249] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"591.633446ms","start":"2026-04-09T06:01:49.76433Z","end":"2026-04-09T06:01:50.355963Z","steps":["trace[105099249] 'agreement among raft nodes before linearized reading' (duration: 588.50655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.356144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.764322Z","time spent":"591.785201ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-09T06:01:50.357974Z","caller":"traceutil/trace.go:171","msg":"trace[2016525671] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"593.975573ms","start":"2026-04-09T06:01:49.761863Z","end":"2026-04-09T06:01:50.355839Z","steps":["trace[2016525671] 'agreement among raft nodes before linearized reading' (duration: 591.132144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:50.358165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:49.761835Z","time spent":"596.24505ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T06:02:19.974074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.789805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:19.974156Z","caller":"traceutil/trace.go:171","msg":"trace[1723306989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"265.937608ms","start":"2026-04-09T06:02:19.7082Z","end":"2026-04-09T06:02:19.974137Z","steps":["trace[1723306989] 'range keys from in-memory index tree' (duration: 265.688641ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:19.974388Z","caller":"traceutil/trace.go:171","msg":"trace[845168309] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"265.997139ms","start":"2026-04-09T06:02:19.708351Z","end":"2026-04-09T06:02:19.974348Z","steps":["trace[845168309] 'process raft request' (duration: 185.960945ms)","trace[845168309] 'compare' (duration: 79.804458ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:20.324207Z","caller":"traceutil/trace.go:171","msg":"trace[506991998] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"126.583396ms","start":"2026-04-09T06:02:20.197586Z","end":"2026-04-09T06:02:20.324169Z","steps":["trace[506991998] 'process raft request' (duration: 80.948545ms)","trace[506991998] 'compare' (duration: 45.320584ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:20.324496Z","caller":"traceutil/trace.go:171","msg":"trace[1904854823] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"125.381809ms","start":"2026-04-09T06:02:20.199046Z","end":"2026-04-09T06:02:20.324428Z","steps":["trace[1904854823] 'process raft request' (duration: 125.170404ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.32482Z","caller":"traceutil/trace.go:171","msg":"trace[950910895] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"117.974438ms","start":"2026-04-09T06:02:20.206823Z","end":"2026-04-09T06:02:20.324798Z","steps":["trace[950910895] 'process raft request' (duration: 117.669841ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.324913Z","caller":"traceutil/trace.go:171","msg":"trace[595873661] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"124.086149ms","start":"2026-04-09T06:02:20.200818Z","end":"2026-04-09T06:02:20.324905Z","steps":["trace[595873661] 'process raft request' (duration: 123.467375ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.324982Z","caller":"traceutil/trace.go:171","msg":"trace[688174189] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"122.323247ms","start":"2026-04-09T06:02:20.202653Z","end":"2026-04-09T06:02:20.324976Z","steps":["trace[688174189] 'process raft request' (duration: 121.729224ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.325056Z","caller":"traceutil/trace.go:171","msg":"trace[900430972] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"117.847545ms","start":"2026-04-09T06:02:20.207202Z","end":"2026-04-09T06:02:20.325049Z","steps":["trace[900430972] 'process raft request' (duration: 117.356324ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.325043Z","caller":"traceutil/trace.go:171","msg":"trace[1815008751] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"116.926363ms","start":"2026-04-09T06:02:20.208098Z","end":"2026-04-09T06:02:20.325025Z","steps":["trace[1815008751] 'process raft request' (duration: 116.522184ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:20.325145Z","caller":"traceutil/trace.go:171","msg":"trace[374972722] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"115.868769ms","start":"2026-04-09T06:02:20.20927Z","end":"2026-04-09T06:02:20.325139Z","steps":["trace[374972722] 'process raft request' (duration: 115.417019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.065013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.558169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:02:21.065173Z","caller":"traceutil/trace.go:171","msg":"trace[1802546770] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"731.61559ms","start":"2026-04-09T06:02:20.333522Z","end":"2026-04-09T06:02:21.065138Z","steps":["trace[1802546770] 'process raft request' (duration: 133.545028ms)","trace[1802546770] 'compare' (duration: 597.446627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:02:21.065274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.333499Z","time spent":"731.725372ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:21.104457Z","caller":"traceutil/trace.go:171","msg":"trace[291084354] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"769.067592ms","start":"2026-04-09T06:02:20.335357Z","end":"2026-04-09T06:02:21.104425Z","steps":["trace[291084354] 'process raft request' (duration: 768.776986ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:21.105324Z","caller":"traceutil/trace.go:171","msg":"trace[558278397] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"762.541062ms","start":"2026-04-09T06:02:20.341931Z","end":"2026-04-09T06:02:21.104472Z","steps":["trace[558278397] 'process raft request' (duration: 762.312647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.10575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.335336Z","time spent":"770.2845ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:02:21.105852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.341916Z","time spent":"763.810871ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:02:21.105836Z","caller":"traceutil/trace.go:171","msg":"trace[871180944] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"763.661497ms","start":"2026-04-09T06:02:20.342144Z","end":"2026-04-09T06:02:21.105805Z","steps":["trace[871180944] 'process raft request' (duration: 762.194943ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:21.106072Z","caller":"traceutil/trace.go:171","msg":"trace[295693456] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"762.05053ms","start":"2026-04-09T06:02:20.343969Z","end":"2026-04-09T06:02:21.106019Z","steps":["trace[295693456] 'process raft request' (duration: 760.440474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.106515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.343948Z","time spent":"762.244294ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:21.106752Z","caller":"traceutil/trace.go:171","msg":"trace[1750040601] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"760.962775ms","start":"2026-04-09T06:02:20.345331Z","end":"2026-04-09T06:02:21.106293Z","steps":["trace[1750040601] 'process raft request' (duration: 759.146224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.106939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.342125Z","time spent":"763.852951ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:02:21.107027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.345302Z","time spent":"761.508457ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:21.111344Z","caller":"traceutil/trace.go:171","msg":"trace[1477604663] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"764.599669ms","start":"2026-04-09T06:02:20.34673Z","end":"2026-04-09T06:02:21.111329Z","steps":["trace[1477604663] 'process raft request' (duration: 759.786868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.111418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.346706Z","time spent":"764.684151ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:02:21.113293Z","caller":"traceutil/trace.go:171","msg":"trace[1757508774] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"760.219719ms","start":"2026-04-09T06:02:20.353043Z","end":"2026-04-09T06:02:21.113263Z","steps":["trace[1757508774] 'process raft request' (duration: 759.738948ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:21.113305Z","caller":"traceutil/trace.go:171","msg":"trace[2009811353] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"765.770146ms","start":"2026-04-09T06:02:20.347484Z","end":"2026-04-09T06:02:21.113254Z","steps":["trace[2009811353] 'process raft request' (duration: 762.078921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.113428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.353035Z","time spent":"760.320781ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:02:21.113581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.347477Z","time spent":"765.982321ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:21.113694Z","caller":"traceutil/trace.go:171","msg":"trace[487181306] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"756.320658ms","start":"2026-04-09T06:02:20.357356Z","end":"2026-04-09T06:02:21.113677Z","steps":["trace[487181306] 'process raft request' (duration: 755.578441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.113767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.357345Z","time spent":"756.381359ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:21.11665Z","caller":"traceutil/trace.go:171","msg":"trace[803206804] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"707.856502ms","start":"2026-04-09T06:02:20.408765Z","end":"2026-04-09T06:02:21.116622Z","steps":["trace[803206804] 'process raft request' (duration: 704.389612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.116789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.408743Z","time spent":"707.979134ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:02:21.117Z","caller":"traceutil/trace.go:171","msg":"trace[2035482850] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"702.218122ms","start":"2026-04-09T06:02:20.414738Z","end":"2026-04-09T06:02:21.116957Z","steps":["trace[2035482850] 'read index received' (duration: 52.204203ms)","trace[2035482850] 'applied index is now lower than readState.Index' (duration: 650.012819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:02:21.117601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.865207ms","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-04-09T06:02:21.117651Z","caller":"traceutil/trace.go:171","msg":"trace[1811747448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"702.942288ms","start":"2026-04-09T06:02:20.414695Z","end":"2026-04-09T06:02:21.117637Z","steps":["trace[1811747448] 'agreement among raft nodes before linearized reading' (duration: 702.789755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:21.117678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:20.414683Z","time spent":"702.98985ms","remote":"127.0.0.1:57864","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-04-09T06:02:27.28775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.476812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-09T06:02:27.287866Z","caller":"traceutil/trace.go:171","msg":"trace[2129054330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"112.652355ms","start":"2026-04-09T06:02:27.175186Z","end":"2026-04-09T06:02:27.287839Z","steps":["trace[2129054330] 'range keys from in-memory index tree' (duration: 112.247996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:27.288291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.44346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T06:02:27.288416Z","caller":"traceutil/trace.go:171","msg":"trace[1283019240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"115.651134ms","start":"2026-04-09T06:02:27.172735Z","end":"2026-04-09T06:02:27.288386Z","steps":["trace[1283019240] 'range keys from in-memory index tree' (duration: 115.189544ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:27.370887Z","caller":"traceutil/trace.go:171","msg":"trace[1532470508] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"174.665095ms","start":"2026-04-09T06:02:27.196188Z","end":"2026-04-09T06:02:27.370853Z","steps":["trace[1532470508] 'process raft request' (duration: 173.041718ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:27.579573Z","caller":"traceutil/trace.go:171","msg":"trace[1097310676] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"152.440883ms","start":"2026-04-09T06:02:27.427101Z","end":"2026-04-09T06:02:27.579542Z","steps":["trace[1097310676] 'process raft request' (duration: 152.245328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:28.138226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.902102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:02:28.138466Z","caller":"traceutil/trace.go:171","msg":"trace[49872202] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"410.240153ms","start":"2026-04-09T06:02:27.728201Z","end":"2026-04-09T06:02:28.138441Z","steps":["trace[49872202] 'process raft request' (duration: 93.003212ms)","trace[49872202] 'compare' (duration: 316.709318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:02:28.138557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:27.728177Z","time spent":"410.343426ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:28.306103Z","caller":"traceutil/trace.go:171","msg":"trace[888453534] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"285.126089ms","start":"2026-04-09T06:02:28.020956Z","end":"2026-04-09T06:02:28.306082Z","steps":["trace[888453534] 'read index received' (duration: 282.689554ms)","trace[888453534] 'applied index is now lower than readState.Index' (duration: 2.435705ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:28.306171Z","caller":"traceutil/trace.go:171","msg":"trace[1858899375] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"286.045491ms","start":"2026-04-09T06:02:28.020091Z","end":"2026-04-09T06:02:28.306137Z","steps":["trace[1858899375] 'process raft request' (duration: 284.103817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:28.306258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.282552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-09T06:02:28.306292Z","caller":"traceutil/trace.go:171","msg":"trace[1582465005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"285.391656ms","start":"2026-04-09T06:02:28.020892Z","end":"2026-04-09T06:02:28.306283Z","steps":["trace[1582465005] 'agreement among raft nodes before linearized reading' (duration: 285.291453ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:17.923819Z","caller":"traceutil/trace.go:171","msg":"trace[2094328947] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"223.152283ms","start":"2026-04-09T06:03:17.700636Z","end":"2026-04-09T06:03:17.923788Z","steps":["trace[2094328947] 'read index received' (duration: 222.890256ms)","trace[2094328947] 'applied index is now lower than readState.Index' (duration: 261.007µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:03:17.924219Z","caller":"traceutil/trace.go:171","msg":"trace[2022821559] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"227.421136ms","start":"2026-04-09T06:03:17.696746Z","end":"2026-04-09T06:03:17.924167Z","steps":["trace[2022821559] 'process raft request' (duration: 226.856152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:03:17.924375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.721587ms","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-04-09T06:03:17.924419Z","caller":"traceutil/trace.go:171","msg":"trace[992821537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"223.826389ms","start":"2026-04-09T06:03:17.70058Z","end":"2026-04-09T06:03:17.924407Z","steps":["trace[992821537] 'agreement among raft nodes before linearized reading' (duration: 223.663084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:18.208165Z","caller":"traceutil/trace.go:171","msg":"trace[493688333] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"279.733973ms","start":"2026-04-09T06:03:17.928399Z","end":"2026-04-09T06:03:18.208133Z","steps":["trace[493688333] 'process raft request' (duration: 279.489607ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:18.232215Z","caller":"traceutil/trace.go:171","msg":"trace[1934131940] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"182.875836ms","start":"2026-04-09T06:03:18.049308Z","end":"2026-04-09T06:03:18.232184Z","steps":["trace[1934131940] 'process raft request' (duration: 182.820484ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:18.232611Z","caller":"traceutil/trace.go:171","msg":"trace[1144409062] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"303.183167ms","start":"2026-04-09T06:03:17.929285Z","end":"2026-04-09T06:03:18.232468Z","steps":["trace[1144409062] 'process raft request' (duration: 302.660164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:03:18.2329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:03:17.929256Z","time spent":"303.456604ms","remote":"127.0.0.1:57864","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-04-09T06:03:44.967501Z","caller":"traceutil/trace.go:171","msg":"trace[588534475] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"228.792799ms","start":"2026-04-09T06:03:44.738673Z","end":"2026-04-09T06:03:44.967466Z","steps":["trace[588534475] 'process raft request' (duration: 228.580584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.894379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.616387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T06:04:16.894862Z","caller":"traceutil/trace.go:171","msg":"trace[1415286121] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"346.330591ms","start":"2026-04-09T06:04:16.548506Z","end":"2026-04-09T06:04:16.894837Z","steps":["trace[1415286121] 'read index received' (duration: 148.256623ms)","trace[1415286121] 'applied index is now lower than readState.Index' (duration: 198.053348ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:04:16.895065Z","caller":"traceutil/trace.go:171","msg":"trace[1508250853] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"355.235926ms","start":"2026-04-09T06:04:16.539779Z","end":"2026-04-09T06:04:16.895015Z","steps":["trace[1508250853] 'process raft request' (duration: 354.859718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.895247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:04:16.539743Z","time spent":"355.413881ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:04:16.895352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.797913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T06:04:16.895433Z","caller":"traceutil/trace.go:171","msg":"trace[176822920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"346.923425ms","start":"2026-04-09T06:04:16.548467Z","end":"2026-04-09T06:04:16.89539Z","steps":["trace[176822920] 'agreement among raft nodes before linearized reading' (duration: 346.538376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.895754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.362112ms","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-04-09T06:04:16.895794Z","caller":"traceutil/trace.go:171","msg":"trace[219338540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"346.433833ms","start":"2026-04-09T06:04:16.549351Z","end":"2026-04-09T06:04:16.895785Z","steps":["trace[219338540] 'agreement among raft nodes before linearized reading' (duration: 346.260069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.89582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:04:16.549305Z","time spent":"346.510004ms","remote":"127.0.0.1:57864","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-04-09T06:04:16.895977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.674892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T06:04:16.896005Z","caller":"traceutil/trace.go:171","msg":"trace[1114094139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"155.745124ms","start":"2026-04-09T06:04:16.740251Z","end":"2026-04-09T06:04:16.895996Z","steps":["trace[1114094139] 'agreement among raft nodes before linearized reading' (duration: 155.683592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.896096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.175653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:04:16.896118Z","caller":"traceutil/trace.go:171","msg":"trace[717794914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"189.242594ms","start":"2026-04-09T06:04:16.706869Z","end":"2026-04-09T06:04:16.896112Z","steps":["trace[717794914] 'agreement among raft nodes before linearized reading' (duration: 189.211084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.896217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.53748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:04:16.89624Z","caller":"traceutil/trace.go:171","msg":"trace[370172733] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1271; }","duration":"258.616731ms","start":"2026-04-09T06:04:16.637618Z","end":"2026-04-09T06:04:16.896234Z","steps":["trace[370172733] 'agreement among raft nodes before linearized reading' (duration: 258.584871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:16.896377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:04:16.548455Z","time spent":"347.016778ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T06:04:17.068139Z","caller":"traceutil/trace.go:171","msg":"trace[1222903650] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"111.354252ms","start":"2026-04-09T06:04:16.956755Z","end":"2026-04-09T06:04:17.068109Z","steps":["trace[1222903650] 'process raft request' (duration: 111.28199ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:04:17.068152Z","caller":"traceutil/trace.go:171","msg":"trace[862899184] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"112.574161ms","start":"2026-04-09T06:04:16.955538Z","end":"2026-04-09T06:04:17.068112Z","steps":["trace[862899184] 'process raft request' (duration: 59.950399ms)","trace[862899184] 'compare' (duration: 52.383416ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:04:32.674242Z","caller":"traceutil/trace.go:171","msg":"trace[1054555585] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"333.45075ms","start":"2026-04-09T06:04:32.340769Z","end":"2026-04-09T06:04:32.67422Z","steps":["trace[1054555585] 'read index received' (duration: 333.339437ms)","trace[1054555585] 'applied index is now lower than readState.Index' (duration: 110.133µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:04:32.674293Z","caller":"traceutil/trace.go:171","msg":"trace[567084712] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"573.228727ms","start":"2026-04-09T06:04:32.101039Z","end":"2026-04-09T06:04:32.674267Z","steps":["trace[567084712] 'process raft request' (duration: 573.033592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:32.674458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.688466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T06:04:32.674459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:04:32.101011Z","time spent":"573.353339ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:04:32.674504Z","caller":"traceutil/trace.go:171","msg":"trace[727842387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1375; }","duration":"333.783898ms","start":"2026-04-09T06:04:32.340709Z","end":"2026-04-09T06:04:32.674493Z","steps":["trace[727842387] 'agreement among raft nodes before linearized reading' (duration: 333.601144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:04:32.674535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:04:32.340686Z","time spent":"333.841789ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T06:04:32.675453Z","caller":"traceutil/trace.go:171","msg":"trace[1916275909] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"123.055914ms","start":"2026-04-09T06:04:32.552366Z","end":"2026-04-09T06:04:32.675422Z","steps":["trace[1916275909] 'process raft request' (duration: 122.902011ms)"],"step_count":1}