{"level":"warn","ts":"2026-03-25T16:48:41.012365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:48:41.012446Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243: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.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243: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-03-25T16:48:41.012605Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:48:41.01262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-03-25T16:48:41.012712Z","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-03-25T16:48:41.013387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-03-25T16:48:41.013468Z","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.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243: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-03-25T16:48:41.017271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.626259ms"} {"level":"info","ts":"2026-03-25T16:48:41.023971Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-03-25T16:48:41.024169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T16:48:41.024258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-03-25T16:48:41.02428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T16:48:41.024291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-03-25T16:48:41.02443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-03-25T16:48:41.029431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T16:48:41.030663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T16:48:41.032097Z","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-03-25T16:48:41.034607Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T16:48:41.034834Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T16:48:41.035975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-03-25T16:48:41.036275Z","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-03-25T16:48:41.038569Z","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-03-25T16:48:41.038643Z","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-03-25T16:48:41.039368Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-03-25T16:48:41.047643Z","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-03-25T16:48:41.047714Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-03-25T16:48:41.047826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-03-25T16:48:41.047924Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T16:48:41.047968Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T16:48:41.624995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T16:48:41.625077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T16:48:41.625119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-03-25T16:48:41.625145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-03-25T16:48:41.625168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-03-25T16:48:41.625185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-03-25T16:48:41.625203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-03-25T16:48:41.626145Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:48:41.626982Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T16:48:41.62699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:48:41.627061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:48:41.627945Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T16:48:41.628023Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T16:48:41.628501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:48:41.628644Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:48:41.628687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:48:41.630289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T16:48:41.630429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"warn","ts":"2026-03-25T16:48:47.381476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.307855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:48:47.381615Z","caller":"traceutil/trace.go:171","msg":"trace[1534662871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:267; }","duration":"235.511759ms","start":"2026-03-25T16:48:47.146083Z","end":"2026-03-25T16:48:47.381595Z","steps":["trace[1534662871] 'range keys from in-memory index tree' (duration: 235.188933ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:48:47.607867Z","caller":"traceutil/trace.go:171","msg":"trace[984648174] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"323.205867ms","start":"2026-03-25T16:48:47.284637Z","end":"2026-03-25T16:48:47.607843Z","steps":["trace[984648174] 'read index received' (duration: 323.095726ms)","trace[984648174] 'applied index is now lower than readState.Index' (duration: 109.471µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:48:47.607978Z","caller":"traceutil/trace.go:171","msg":"trace[212575327] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"323.941492ms","start":"2026-03-25T16:48:47.28403Z","end":"2026-03-25T16:48:47.607971Z","steps":["trace[212575327] 'process raft request' (duration: 323.744198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.608354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.283989Z","time spent":"324.009342ms","remote":"127.0.0.1:49794","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-03-25T16:48:47.608438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.771127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-03-25T16:48:47.608543Z","caller":"traceutil/trace.go:171","msg":"trace[191590783] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:267; }","duration":"323.92814ms","start":"2026-03-25T16:48:47.284589Z","end":"2026-03-25T16:48:47.608517Z","steps":["trace[191590783] 'agreement among raft nodes before linearized reading' (duration: 323.640515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.608609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.284577Z","time spent":"324.018581ms","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-03-25T16:48:47.810132Z","caller":"traceutil/trace.go:171","msg":"trace[223394145] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"202.159828ms","start":"2026-03-25T16:48:47.607936Z","end":"2026-03-25T16:48:47.810096Z","steps":["trace[223394145] 'read index received' (duration: 202.053407ms)","trace[223394145] 'applied index is now lower than readState.Index' (duration: 105.131µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:48:47.810298Z","caller":"traceutil/trace.go:171","msg":"trace[689127045] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"523.399021ms","start":"2026-03-25T16:48:47.286889Z","end":"2026-03-25T16:48:47.810288Z","steps":["trace[689127045] 'process raft request' (duration: 523.104276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.810427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.286854Z","time spent":"523.484873ms","remote":"127.0.0.1:49794","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-03-25T16:48:47.810791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.529329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T16:48:47.810836Z","caller":"traceutil/trace.go:171","msg":"trace[558192849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:267; }","duration":"405.622841ms","start":"2026-03-25T16:48:47.4052Z","end":"2026-03-25T16:48:47.810823Z","steps":["trace[558192849] 'agreement among raft nodes before linearized reading' (duration: 405.533589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.810867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.405179Z","time spent":"405.682083ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T16:48:47.811053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.475717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-25T16:48:47.811089Z","caller":"traceutil/trace.go:171","msg":"trace[1810718312] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"426.510927ms","start":"2026-03-25T16:48:47.384569Z","end":"2026-03-25T16:48:47.81108Z","steps":["trace[1810718312] 'agreement among raft nodes before linearized reading' (duration: 426.451506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.811116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.384503Z","time spent":"426.607369ms","remote":"127.0.0.1:49724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-25T16:48:47.811267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.505348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:48:47.811301Z","caller":"traceutil/trace.go:171","msg":"trace[482687736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"521.569268ms","start":"2026-03-25T16:48:47.289723Z","end":"2026-03-25T16:48:47.811293Z","steps":["trace[482687736] 'agreement among raft nodes before linearized reading' (duration: 521.522597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.811328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:48:47.289713Z","time spent":"521.609179ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T16:48:47.852542Z","caller":"traceutil/trace.go:171","msg":"trace[1664217351] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"204.101703ms","start":"2026-03-25T16:48:47.6484Z","end":"2026-03-25T16:48:47.852501Z","steps":["trace[1664217351] 'process raft request' (duration: 203.846609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:48:47.853148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.409802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-03-25T16:48:47.85327Z","caller":"traceutil/trace.go:171","msg":"trace[376929545] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:268; }","duration":"243.612215ms","start":"2026-03-25T16:48:47.609634Z","end":"2026-03-25T16:48:47.853246Z","steps":["trace[376929545] 'agreement among raft nodes before linearized reading' (duration: 243.385831ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:35.681391Z","caller":"traceutil/trace.go:171","msg":"trace[1952105782] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"102.432895ms","start":"2026-03-25T16:49:35.578932Z","end":"2026-03-25T16:49:35.681365Z","steps":["trace[1952105782] 'process raft request' (duration: 76.464547ms)","trace[1952105782] 'compare' (duration: 25.825965ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:35.682351Z","caller":"traceutil/trace.go:171","msg":"trace[942902380] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"101.963214ms","start":"2026-03-25T16:49:35.580351Z","end":"2026-03-25T16:49:35.682314Z","steps":["trace[942902380] 'process raft request' (duration: 101.526787ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:35.682479Z","caller":"traceutil/trace.go:171","msg":"trace[1304417240] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"101.859253ms","start":"2026-03-25T16:49:35.580589Z","end":"2026-03-25T16:49:35.682448Z","steps":["trace[1304417240] 'process raft request' (duration: 101.407515ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:56.480142Z","caller":"traceutil/trace.go:171","msg":"trace[482168790] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"279.179635ms","start":"2026-03-25T16:49:56.200926Z","end":"2026-03-25T16:49:56.480105Z","steps":["trace[482168790] 'read index received' (duration: 278.517663ms)","trace[482168790] 'applied index is now lower than readState.Index' (duration: 660.142µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:56.480215Z","caller":"traceutil/trace.go:171","msg":"trace[1337642837] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"287.489531ms","start":"2026-03-25T16:49:56.192689Z","end":"2026-03-25T16:49:56.480179Z","steps":["trace[1337642837] 'process raft request' (duration: 286.949571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.480399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.451271ms","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-03-25T16:49:56.48044Z","caller":"traceutil/trace.go:171","msg":"trace[1263569701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"279.548283ms","start":"2026-03-25T16:49:56.20088Z","end":"2026-03-25T16:49:56.480428Z","steps":["trace[1263569701] 'agreement among raft nodes before linearized reading' (duration: 279.38387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.480552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.545913ms","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-03-25T16:49:56.480665Z","caller":"traceutil/trace.go:171","msg":"trace[132034845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"250.720346ms","start":"2026-03-25T16:49:56.229918Z","end":"2026-03-25T16:49:56.480638Z","steps":["trace[132034845] 'agreement among raft nodes before linearized reading' (duration: 250.36563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.927774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.300341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T16:49:56.928Z","caller":"traceutil/trace.go:171","msg":"trace[366927133] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"441.331973ms","start":"2026-03-25T16:49:56.486632Z","end":"2026-03-25T16:49:56.927964Z","steps":["trace[366927133] 'process raft request' (duration: 20.293058ms)","trace[366927133] 'compare' (duration: 420.106808ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:56.928162Z","caller":"traceutil/trace.go:171","msg":"trace[740891176] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"373.620392ms","start":"2026-03-25T16:49:56.55452Z","end":"2026-03-25T16:49:56.92814Z","steps":["trace[740891176] 'process raft request' (duration: 373.573631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.928206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.486594Z","time spent":"441.500196ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:49:56.928245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.554502Z","time spent":"373.710084ms","remote":"127.0.0.1:50068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T16:49:56.92847Z","caller":"traceutil/trace.go:171","msg":"trace[51132265] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"417.909758ms","start":"2026-03-25T16:49:56.510526Z","end":"2026-03-25T16:49:56.928435Z","steps":["trace[51132265] 'process raft request' (duration: 417.52822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.928674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.510511Z","time spent":"418.061121ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:49:56.928956Z","caller":"traceutil/trace.go:171","msg":"trace[345824602] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"421.062627ms","start":"2026-03-25T16:49:56.507291Z","end":"2026-03-25T16:49:56.928354Z","steps":["trace[345824602] 'process raft request' (duration: 420.689719ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:56.929109Z","caller":"traceutil/trace.go:171","msg":"trace[2110189148] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"441.334673ms","start":"2026-03-25T16:49:56.487161Z","end":"2026-03-25T16:49:56.928496Z","steps":["trace[2110189148] 'read index received' (duration: 19.8923ms)","trace[2110189148] 'applied index is now lower than readState.Index' (duration: 421.440133ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:56.929245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.072546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98z6q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-25T16:49:56.929295Z","caller":"traceutil/trace.go:171","msg":"trace[251313054] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98z6q; range_end:; response_count:1; response_revision:665; }","duration":"442.156609ms","start":"2026-03-25T16:49:56.487121Z","end":"2026-03-25T16:49:56.929277Z","steps":["trace[251313054] 'agreement among raft nodes before linearized reading' (duration: 441.963296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.929325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.487099Z","time spent":"442.21889ms","remote":"127.0.0.1:49794","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-98z6q\" "} {"level":"warn","ts":"2026-03-25T16:49:56.929357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.588139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:56.929403Z","caller":"traceutil/trace.go:171","msg":"trace[1025657861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"383.69165ms","start":"2026-03-25T16:49:56.545695Z","end":"2026-03-25T16:49:56.929387Z","steps":["trace[1025657861] 'agreement among raft nodes before linearized reading' (duration: 383.571088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:56.929443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.545675Z","time spent":"383.759962ms","remote":"127.0.0.1:49622","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-03-25T16:49:56.92991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.507266Z","time spent":"421.796519ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:49:57.315158Z","caller":"traceutil/trace.go:171","msg":"trace[2102829023] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"369.190561ms","start":"2026-03-25T16:49:56.945928Z","end":"2026-03-25T16:49:57.315119Z","steps":["trace[2102829023] 'process raft request' (duration: 368.829365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:57.315373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:56.945916Z","time spent":"369.349614ms","remote":"127.0.0.1:49794","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-03-25T16:49:57.680909Z","caller":"traceutil/trace.go:171","msg":"trace[915722199] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"131.290543ms","start":"2026-03-25T16:49:57.549587Z","end":"2026-03-25T16:49:57.680878Z","steps":["trace[915722199] 'read index received' (duration: 130.699003ms)","trace[915722199] 'applied index is now lower than readState.Index' (duration: 589.36µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:57.681017Z","caller":"traceutil/trace.go:171","msg":"trace[1279947851] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"328.924211ms","start":"2026-03-25T16:49:57.352062Z","end":"2026-03-25T16:49:57.680987Z","steps":["trace[1279947851] 'process raft request' (duration: 328.27928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:57.681379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:57.352019Z","time spent":"329.245467ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:49:57.681113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.503838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:57.681565Z","caller":"traceutil/trace.go:171","msg":"trace[1171971950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"131.967526ms","start":"2026-03-25T16:49:57.549532Z","end":"2026-03-25T16:49:57.681499Z","steps":["trace[1171971950] 'agreement among raft nodes before linearized reading' (duration: 131.474726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:50:13.805555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.472023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qfkxh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-03-25T16:50:13.805662Z","caller":"traceutil/trace.go:171","msg":"trace[893293239] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qfkxh; range_end:; response_count:1; response_revision:830; }","duration":"213.646146ms","start":"2026-03-25T16:50:13.591991Z","end":"2026-03-25T16:50:13.805637Z","steps":["trace[893293239] 'range keys from in-memory index tree' (duration: 213.221589ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:50:13.923085Z","caller":"traceutil/trace.go:171","msg":"trace[1012492678] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"108.913358ms","start":"2026-03-25T16:50:13.814141Z","end":"2026-03-25T16:50:13.923054Z","steps":["trace[1012492678] 'process raft request' (duration: 108.701003ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:09.685601Z","caller":"traceutil/trace.go:171","msg":"trace[26577778] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1154; }","duration":"340.207843ms","start":"2026-03-25T16:51:09.345325Z","end":"2026-03-25T16:51:09.685532Z","steps":["trace[26577778] 'read index received' (duration: 39.813139ms)","trace[26577778] 'applied index is now lower than readState.Index' (duration: 300.392974ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:51:09.685701Z","caller":"traceutil/trace.go:171","msg":"trace[906820687] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"342.578506ms","start":"2026-03-25T16:51:09.343066Z","end":"2026-03-25T16:51:09.685644Z","steps":["trace[906820687] 'process raft request' (duration: 306.145239ms)","trace[906820687] 'compare' (duration: 36.0555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:51:09.685952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:51:09.343047Z","time spent":"342.772449ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:51:09.685951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.59752ms","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-03-25T16:51:09.686349Z","caller":"traceutil/trace.go:171","msg":"trace[2141701897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"341.023388ms","start":"2026-03-25T16:51:09.345302Z","end":"2026-03-25T16:51:09.686325Z","steps":["trace[2141701897] 'agreement among raft nodes before linearized reading' (duration: 340.432056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:09.686403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:51:09.345295Z","time spent":"341.092019ms","remote":"127.0.0.1:49846","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-03-25T16:51:09.688178Z","caller":"traceutil/trace.go:171","msg":"trace[172998068] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"258.954227ms","start":"2026-03-25T16:51:09.4292Z","end":"2026-03-25T16:51:09.688154Z","steps":["trace[172998068] 'process raft request' (duration: 258.797584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:09.688293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.086053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T16:51:09.688314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.776154ms","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-03-25T16:51:09.688373Z","caller":"traceutil/trace.go:171","msg":"trace[481394065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"140.184944ms","start":"2026-03-25T16:51:09.548171Z","end":"2026-03-25T16:51:09.688356Z","steps":["trace[481394065] 'agreement among raft nodes before linearized reading' (duration: 140.027062ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:09.689927Z","caller":"traceutil/trace.go:171","msg":"trace[526890977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"334.11697ms","start":"2026-03-25T16:51:09.354517Z","end":"2026-03-25T16:51:09.688634Z","steps":["trace[526890977] 'agreement among raft nodes before linearized reading' (duration: 333.621882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:09.690019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:51:09.354496Z","time spent":"335.502946ms","remote":"127.0.0.1:49846","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":"warn","ts":"2026-03-25T16:51:11.9468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.197406ms","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-03-25T16:51:11.946964Z","caller":"traceutil/trace.go:171","msg":"trace[1715054393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1119; }","duration":"121.3947ms","start":"2026-03-25T16:51:11.825498Z","end":"2026-03-25T16:51:11.946893Z","steps":["trace[1715054393] 'range keys from in-memory index tree' (duration: 120.88149ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:23.873262Z","caller":"traceutil/trace.go:171","msg":"trace[609261292] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"111.471919ms","start":"2026-03-25T16:51:23.761757Z","end":"2026-03-25T16:51:23.873229Z","steps":["trace[609261292] 'read index received' (duration: 111.229294ms)","trace[609261292] 'applied index is now lower than readState.Index' (duration: 241.405µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:51:23.87342Z","caller":"traceutil/trace.go:171","msg":"trace[262950568] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"117.837346ms","start":"2026-03-25T16:51:23.755521Z","end":"2026-03-25T16:51:23.873359Z","steps":["trace[262950568] 'process raft request' (duration: 117.50092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:23.873609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.822945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-25T16:51:23.873609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.279598ms","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-03-25T16:51:23.873666Z","caller":"traceutil/trace.go:171","msg":"trace[1646937823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"111.939118ms","start":"2026-03-25T16:51:23.761713Z","end":"2026-03-25T16:51:23.873652Z","steps":["trace[1646937823] 'agreement among raft nodes before linearized reading' (duration: 111.686353ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:23.873683Z","caller":"traceutil/trace.go:171","msg":"trace[1916445968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"104.434941ms","start":"2026-03-25T16:51:23.769229Z","end":"2026-03-25T16:51:23.873664Z","steps":["trace[1916445968] 'agreement among raft nodes before linearized reading' (duration: 104.228537ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:23.986742Z","caller":"traceutil/trace.go:171","msg":"trace[1009091134] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"105.765234ms","start":"2026-03-25T16:51:23.880948Z","end":"2026-03-25T16:51:23.986714Z","steps":["trace[1009091134] 'process raft request' (duration: 105.628742ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:23.986751Z","caller":"traceutil/trace.go:171","msg":"trace[911826926] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"105.779614ms","start":"2026-03-25T16:51:23.880951Z","end":"2026-03-25T16:51:23.986731Z","steps":["trace[911826926] 'process raft request' (duration: 105.721293ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:40.789554Z","caller":"traceutil/trace.go:171","msg":"trace[277112763] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"240.898856ms","start":"2026-03-25T16:51:40.548628Z","end":"2026-03-25T16:51:40.789527Z","steps":["trace[277112763] 'read index received' (duration: 240.56731ms)","trace[277112763] 'applied index is now lower than readState.Index' (duration: 330.456µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:51:40.789635Z","caller":"traceutil/trace.go:171","msg":"trace[204859281] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"311.429466ms","start":"2026-03-25T16:51:40.478157Z","end":"2026-03-25T16:51:40.789587Z","steps":["trace[204859281] 'process raft request' (duration: 311.137831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:40.789767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.10656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:51:40.789854Z","caller":"traceutil/trace.go:171","msg":"trace[2128561044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"241.247512ms","start":"2026-03-25T16:51:40.548585Z","end":"2026-03-25T16:51:40.789833Z","steps":["trace[2128561044] 'agreement among raft nodes before linearized reading' (duration: 241.062699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:40.789832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.546462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T16:51:40.789862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:51:40.478126Z","time spent":"311.612399ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:51:40.789923Z","caller":"traceutil/trace.go:171","msg":"trace[1789785411] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1283; }","duration":"186.705985ms","start":"2026-03-25T16:51:40.603197Z","end":"2026-03-25T16:51:40.789903Z","steps":["trace[1789785411] 'agreement among raft nodes before linearized reading' (duration: 186.547352ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:42.33698Z","caller":"traceutil/trace.go:171","msg":"trace[1852922938] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"172.655908ms","start":"2026-03-25T16:51:42.16429Z","end":"2026-03-25T16:51:42.336946Z","steps":["trace[1852922938] 'process raft request' (duration: 151.327128ms)","trace[1852922938] 'compare' (duration: 21.036725ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:51:42.337Z","caller":"traceutil/trace.go:171","msg":"trace[1185460873] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"172.421444ms","start":"2026-03-25T16:51:42.164547Z","end":"2026-03-25T16:51:42.336968Z","steps":["trace[1185460873] 'process raft request' (duration: 172.273322ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:42.337512Z","caller":"traceutil/trace.go:171","msg":"trace[2050518962] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"162.891919ms","start":"2026-03-25T16:51:42.174019Z","end":"2026-03-25T16:51:42.33691Z","steps":["trace[2050518962] 'process raft request' (duration: 162.847458ms)"],"step_count":1}