{"level":"warn","ts":"2026-04-20T14:08:42.604722Z","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-20T14:08:42.604832Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108: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.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-20T14:08:42.604914Z","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-20T14:08:42.604926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-04-20T14:08:42.604946Z","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-20T14:08:42.605617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-04-20T14:08:42.605686Z","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.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-20T14:08:42.610949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.050401ms"} {"level":"info","ts":"2026-04-20T14:08:42.623418Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-04-20T14:08:42.623507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:08:42.62353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:08:42.62354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:08:42.623551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:08:42.62359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-04-20T14:08:42.633987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:08:42.6371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:08:42.639858Z","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-20T14:08:42.642303Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:08:42.64241Z","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-20T14:08:42.642504Z","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-20T14:08:42.642517Z","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-20T14:08:42.64282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:08:42.64417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-04-20T14:08:42.644545Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-04-20T14:08:42.645957Z","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-20T14:08:42.646128Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:08:42.646152Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:08:42.646242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-04-20T14:08:42.646255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-04-20T14:08:42.824776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:08:42.824928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:08:42.824963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-04-20T14:08:42.824983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:08:42.824995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-04-20T14:08:42.825012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:08:42.825025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-04-20T14:08:42.826444Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:08:42.826456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:08:42.826488Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:42.826687Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:08:42.827304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:08:42.827351Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:08:42.827727Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:42.827815Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:42.827849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:42.829833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:08:42.83485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"info","ts":"2026-04-20T14:08:44.696546Z","caller":"traceutil/trace.go:171","msg":"trace[2133509059] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"216.379688ms","start":"2026-04-20T14:08:44.48014Z","end":"2026-04-20T14:08:44.696519Z","steps":["trace[2133509059] 'process raft request' (duration: 216.179191ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.929452Z","caller":"traceutil/trace.go:171","msg":"trace[363856527] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"448.982338ms","start":"2026-04-20T14:08:44.480428Z","end":"2026-04-20T14:08:44.92941Z","steps":["trace[363856527] 'process raft request' (duration: 448.273352ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.929628Z","caller":"traceutil/trace.go:171","msg":"trace[221335780] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"438.867393ms","start":"2026-04-20T14:08:44.490724Z","end":"2026-04-20T14:08:44.929591Z","steps":["trace[221335780] 'read index received' (duration: 205.691861ms)","trace[221335780] 'applied index is now lower than readState.Index' (duration: 233.174252ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:44.929807Z","caller":"traceutil/trace.go:171","msg":"trace[139973521] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"365.595648ms","start":"2026-04-20T14:08:44.564193Z","end":"2026-04-20T14:08:44.929789Z","steps":["trace[139973521] 'process raft request' (duration: 365.245175ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.929918Z","caller":"traceutil/trace.go:171","msg":"trace[848156677] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"355.132418ms","start":"2026-04-20T14:08:44.574764Z","end":"2026-04-20T14:08:44.929896Z","steps":["trace[848156677] 'process raft request' (duration: 354.721213ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.929909Z","caller":"traceutil/trace.go:171","msg":"trace[47867255] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"354.837628ms","start":"2026-04-20T14:08:44.574991Z","end":"2026-04-20T14:08:44.929828Z","steps":["trace[47867255] 'process raft request' (duration: 354.546777ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.929819Z","caller":"traceutil/trace.go:171","msg":"trace[565178954] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"366.687088ms","start":"2026-04-20T14:08:44.563046Z","end":"2026-04-20T14:08:44.929733Z","steps":["trace[565178954] 'process raft request' (duration: 366.364516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.235641ms","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-20T14:08:44.930302Z","caller":"traceutil/trace.go:171","msg":"trace[1424479300] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"398.441288ms","start":"2026-04-20T14:08:44.531786Z","end":"2026-04-20T14:08:44.930227Z","steps":["trace[1424479300] 'agreement among raft nodes before linearized reading' (duration: 398.120296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.480419Z","time spent":"449.117344ms","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:08:44.930242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.574977Z","time spent":"355.15144ms","remote":"127.0.0.1:50114","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-20T14:08:44.930358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.531765Z","time spent":"398.581984ms","remote":"127.0.0.1:49950","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-20T14:08:44.93038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.345592ms","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":"warn","ts":"2026-04-20T14:08:44.930435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.701753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T14:08:44.93045Z","caller":"traceutil/trace.go:171","msg":"trace[1251518181] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"367.420195ms","start":"2026-04-20T14:08:44.563011Z","end":"2026-04-20T14:08:44.930431Z","steps":["trace[1251518181] 'agreement among raft nodes before linearized reading' (duration: 367.313661ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.930481Z","caller":"traceutil/trace.go:171","msg":"trace[860987523] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"439.750025ms","start":"2026-04-20T14:08:44.490721Z","end":"2026-04-20T14:08:44.930471Z","steps":["trace[860987523] 'agreement among raft nodes before linearized reading' (duration: 439.674022ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.930474Z","caller":"traceutil/trace.go:171","msg":"trace[409841884] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"367.597812ms","start":"2026-04-20T14:08:44.562858Z","end":"2026-04-20T14:08:44.930456Z","steps":["trace[409841884] 'process raft request' (duration: 366.487611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.490697Z","time spent":"439.807387ms","remote":"127.0.0.1:50250","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-20T14:08:44.930534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.564182Z","time spent":"365.673101ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:08:44.930554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.025144ms","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-20T14:08:44.930592Z","caller":"traceutil/trace.go:171","msg":"trace[1988449998] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"316.077336ms","start":"2026-04-20T14:08:44.614502Z","end":"2026-04-20T14:08:44.93058Z","steps":["trace[1988449998] 'agreement among raft nodes before linearized reading' (duration: 316.024724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.563016Z","time spent":"367.133584ms","remote":"127.0.0.1:44038","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-20T14:08:44.9305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.562997Z","time spent":"367.490038ms","remote":"127.0.0.1:49928","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":"warn","ts":"2026-04-20T14:08:44.930639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.596688ms","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-20T14:08:44.930677Z","caller":"traceutil/trace.go:171","msg":"trace[1922384207] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"368.667991ms","start":"2026-04-20T14:08:44.561996Z","end":"2026-04-20T14:08:44.930664Z","steps":["trace[1922384207] 'agreement among raft nodes before linearized reading' (duration: 368.605629ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:44.930053Z","caller":"traceutil/trace.go:171","msg":"trace[1403658138] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"367.302191ms","start":"2026-04-20T14:08:44.562722Z","end":"2026-04-20T14:08:44.930025Z","steps":["trace[1403658138] 'process raft request' (duration: 366.580764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.485929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T14:08:44.930772Z","caller":"traceutil/trace.go:171","msg":"trace[429070004] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:0; response_revision:11; }","duration":"370.544211ms","start":"2026-04-20T14:08:44.56022Z","end":"2026-04-20T14:08:44.930764Z","steps":["trace[429070004] 'agreement among raft nodes before linearized reading' (duration: 370.51061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.562847Z","time spent":"367.691935ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:08:44.930806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.560195Z","time spent":"370.602033ms","remote":"127.0.0.1:49872","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.108\" "} {"level":"info","ts":"2026-04-20T14:08:44.930099Z","caller":"traceutil/trace.go:171","msg":"trace[1738773756] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"367.577861ms","start":"2026-04-20T14:08:44.562514Z","end":"2026-04-20T14:08:44.930092Z","steps":["trace[1738773756] 'process raft request' (duration: 366.73544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.614488Z","time spent":"316.140198ms","remote":"127.0.0.1:49950","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-20T14:08:44.930838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.56271Z","time spent":"368.042358ms","remote":"127.0.0.1:44038","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-20T14:08:44.93094Z","caller":"traceutil/trace.go:171","msg":"trace[1983754510] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"368.034498ms","start":"2026-04-20T14:08:44.562892Z","end":"2026-04-20T14:08:44.930926Z","steps":["trace[1983754510] 'process raft request' (duration: 366.487681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:44.930678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.574745Z","time spent":"355.257043ms","remote":"127.0.0.1:50034","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-20T14:08:44.931022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.562866Z","time spent":"368.114951ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:08:44.930888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.562488Z","time spent":"368.35103ms","remote":"127.0.0.1:43954","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-20T14:08:44.930713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:44.561968Z","time spent":"368.738634ms","remote":"127.0.0.1:49950","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-20T14:09:08.937713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.860254ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:08.937926Z","caller":"traceutil/trace.go:171","msg":"trace[2099401322] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:463; }","duration":"280.107701ms","start":"2026-04-20T14:09:08.657792Z","end":"2026-04-20T14:09:08.937899Z","steps":["trace[2099401322] 'range keys from in-memory index tree' (duration: 279.801603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:08.938283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.360173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:09:08.93843Z","caller":"traceutil/trace.go:171","msg":"trace[1558360324] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"503.078775ms","start":"2026-04-20T14:09:08.43533Z","end":"2026-04-20T14:09:08.938409Z","steps":["trace[1558360324] 'read index received' (duration: 120.997661ms)","trace[1558360324] 'applied index is now lower than readState.Index' (duration: 382.077874ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:08.938543Z","caller":"traceutil/trace.go:171","msg":"trace[1599226926] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"689.020723ms","start":"2026-04-20T14:09:08.249505Z","end":"2026-04-20T14:09:08.938525Z","steps":["trace[1599226926] 'process raft request' (duration: 306.852927ms)","trace[1599226926] 'compare' (duration: 381.233879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:08.938571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.251831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:08.938607Z","caller":"traceutil/trace.go:171","msg":"trace[1870941530] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:464; }","duration":"503.349334ms","start":"2026-04-20T14:09:08.435245Z","end":"2026-04-20T14:09:08.938595Z","steps":["trace[1870941530] 'agreement among raft nodes before linearized reading' (duration: 503.272481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:08.938716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:08.435226Z","time spent":"503.477047ms","remote":"127.0.0.1:49936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-20T14:09:08.9387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.68232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T14:09:08.938755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:08.249479Z","time spent":"689.120287ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:09:08.938798Z","caller":"traceutil/trace.go:171","msg":"trace[1564729037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"433.815534ms","start":"2026-04-20T14:09:08.504955Z","end":"2026-04-20T14:09:08.93877Z","steps":["trace[1564729037] 'agreement among raft nodes before linearized reading' (duration: 433.635408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:08.938865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:08.504925Z","time spent":"433.917146ms","remote":"127.0.0.1:50122","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":"info","ts":"2026-04-20T14:09:09.162882Z","caller":"traceutil/trace.go:171","msg":"trace[1666256044] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"102.440489ms","start":"2026-04-20T14:09:09.060415Z","end":"2026-04-20T14:09:09.162856Z","steps":["trace[1666256044] 'read index received' (duration: 82.624513ms)","trace[1666256044] 'applied index is now lower than readState.Index' (duration: 19.815336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:09.162995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.563642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:09.163018Z","caller":"traceutil/trace.go:171","msg":"trace[1875367190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"102.786208ms","start":"2026-04-20T14:09:09.060224Z","end":"2026-04-20T14:09:09.163011Z","steps":["trace[1875367190] 'agreement among raft nodes before linearized reading' (duration: 102.722576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:09.163027Z","caller":"traceutil/trace.go:171","msg":"trace[1089995011] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"218.684056ms","start":"2026-04-20T14:09:08.944315Z","end":"2026-04-20T14:09:09.162999Z","steps":["trace[1089995011] 'process raft request' (duration: 198.655564ms)","trace[1089995011] 'compare' (duration: 19.798795ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:13.265113Z","caller":"traceutil/trace.go:171","msg":"trace[760114236] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"230.711134ms","start":"2026-04-20T14:09:13.034364Z","end":"2026-04-20T14:09:13.265075Z","steps":["trace[760114236] 'read index received' (duration: 141.164222ms)","trace[760114236] 'applied index is now lower than readState.Index' (duration: 89.545592ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:13.265248Z","caller":"traceutil/trace.go:171","msg":"trace[1394581701] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"287.660579ms","start":"2026-04-20T14:09:12.977535Z","end":"2026-04-20T14:09:13.265196Z","steps":["trace[1394581701] 'process raft request' (duration: 198.018634ms)","trace[1394581701] 'compare' (duration: 89.357777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:13.265437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.612317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:13.265488Z","caller":"traceutil/trace.go:171","msg":"trace[198956995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"206.72501ms","start":"2026-04-20T14:09:13.058749Z","end":"2026-04-20T14:09:13.265474Z","steps":["trace[198956995] 'agreement among raft nodes before linearized reading' (duration: 206.541125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:13.266126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.743052ms","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-20T14:09:13.266237Z","caller":"traceutil/trace.go:171","msg":"trace[1331156177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"231.927408ms","start":"2026-04-20T14:09:13.034284Z","end":"2026-04-20T14:09:13.266212Z","steps":["trace[1331156177] 'agreement among raft nodes before linearized reading' (duration: 230.92052ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:15.453885Z","caller":"traceutil/trace.go:171","msg":"trace[24797654] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"171.57519ms","start":"2026-04-20T14:09:15.282279Z","end":"2026-04-20T14:09:15.453854Z","steps":["trace[24797654] 'read index received' (duration: 170.198232ms)","trace[24797654] 'applied index is now lower than readState.Index' (duration: 1.375808ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:15.454184Z","caller":"traceutil/trace.go:171","msg":"trace[1640075345] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"278.112388ms","start":"2026-04-20T14:09:15.175844Z","end":"2026-04-20T14:09:15.453956Z","steps":["trace[1640075345] 'process raft request' (duration: 277.234164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:15.454401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.111465ms","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-04-20T14:09:15.454686Z","caller":"traceutil/trace.go:171","msg":"trace[1518295114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"172.453824ms","start":"2026-04-20T14:09:15.28221Z","end":"2026-04-20T14:09:15.454664Z","steps":["trace[1518295114] 'agreement among raft nodes before linearized reading' (duration: 171.993722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:15.454867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.243138ms","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-20T14:09:15.454969Z","caller":"traceutil/trace.go:171","msg":"trace[633587190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"172.385132ms","start":"2026-04-20T14:09:15.282557Z","end":"2026-04-20T14:09:15.454942Z","steps":["trace[633587190] 'agreement among raft nodes before linearized reading' (duration: 172.073963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:15.65033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.596083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:09:15.650557Z","caller":"traceutil/trace.go:171","msg":"trace[801458731] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"139.439364ms","start":"2026-04-20T14:09:15.511096Z","end":"2026-04-20T14:09:15.650535Z","steps":["trace[801458731] 'process raft request' (duration: 139.376262ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:15.650621Z","caller":"traceutil/trace.go:171","msg":"trace[1321494763] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"187.251313ms","start":"2026-04-20T14:09:15.4633Z","end":"2026-04-20T14:09:15.650551Z","steps":["trace[1321494763] 'process raft request' (duration: 28.336181ms)","trace[1321494763] 'compare' (duration: 158.47465ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:15.669438Z","caller":"traceutil/trace.go:171","msg":"trace[1079651557] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"156.180236ms","start":"2026-04-20T14:09:15.513227Z","end":"2026-04-20T14:09:15.669407Z","steps":["trace[1079651557] 'process raft request' (duration: 156.051972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:16.137168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.103978ms","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-20T14:09:16.137256Z","caller":"traceutil/trace.go:171","msg":"trace[1189833907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"302.251871ms","start":"2026-04-20T14:09:15.834987Z","end":"2026-04-20T14:09:16.137238Z","steps":["trace[1189833907] 'range keys from in-memory index tree' (duration: 301.82471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:16.137238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.18324ms","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-04-20T14:09:16.13731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:15.834964Z","time spent":"302.334733ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:09:16.137326Z","caller":"traceutil/trace.go:171","msg":"trace[2146744320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"302.324723ms","start":"2026-04-20T14:09:15.83498Z","end":"2026-04-20T14:09:16.137305Z","steps":["trace[2146744320] 'range keys from in-memory index tree' (duration: 301.991274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:16.137365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:15.834957Z","time spent":"302.397646ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:09:17.318109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.13260296s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T14:09:17.426777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.358085ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-20T14:09:17.427079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:16.185374Z","time spent":"1.241695476s","remote":"127.0.0.1:49872","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T14:09:17.427321Z","caller":"traceutil/trace.go:171","msg":"trace[1506156710] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"365.533573ms","start":"2026-04-20T14:09:17.061764Z","end":"2026-04-20T14:09:17.427297Z","steps":["trace[1506156710] 'read index received' (duration: 256.599183ms)","trace[1506156710] 'applied index is now lower than readState.Index' (duration: 108.93298ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:17.427347Z","caller":"traceutil/trace.go:171","msg":"trace[1886204977] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.189062368s","start":"2026-04-20T14:09:16.238251Z","end":"2026-04-20T14:09:17.427313Z","steps":["trace[1886204977] 'process raft request' (duration: 1.188672507s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:17.427498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:16.238222Z","time spent":"1.189197271s","remote":"127.0.0.1:50102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:09:17.427504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.725779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:17.427576Z","caller":"traceutil/trace.go:171","msg":"trace[1658606564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"365.842352ms","start":"2026-04-20T14:09:17.06172Z","end":"2026-04-20T14:09:17.427563Z","steps":["trace[1658606564] 'agreement among raft nodes before linearized reading' (duration: 365.672418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:17.427705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:17.061701Z","time spent":"365.909554ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:09:18.374628Z","caller":"traceutil/trace.go:171","msg":"trace[2080622817] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"168.344363ms","start":"2026-04-20T14:09:18.206251Z","end":"2026-04-20T14:09:18.374596Z","steps":["trace[2080622817] 'process raft request' (duration: 168.124946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:18.37567Z","caller":"traceutil/trace.go:171","msg":"trace[1278020085] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"165.860895ms","start":"2026-04-20T14:09:18.209762Z","end":"2026-04-20T14:09:18.375623Z","steps":["trace[1278020085] 'process raft request' (duration: 165.720882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:32.603673Z","caller":"traceutil/trace.go:171","msg":"trace[2058716501] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"139.872154ms","start":"2026-04-20T14:09:32.463773Z","end":"2026-04-20T14:09:32.603645Z","steps":["trace[2058716501] 'process raft request' (duration: 139.71224ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:32.656797Z","caller":"traceutil/trace.go:171","msg":"trace[556663183] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"192.633079ms","start":"2026-04-20T14:09:32.464138Z","end":"2026-04-20T14:09:32.656771Z","steps":["trace[556663183] 'process raft request' (duration: 192.483045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:35.494679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.524628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:09:35.494786Z","caller":"traceutil/trace.go:171","msg":"trace[1442040837] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"580.832208ms","start":"2026-04-20T14:09:34.913935Z","end":"2026-04-20T14:09:35.494767Z","steps":["trace[1442040837] 'read index received' (duration: 305.202478ms)","trace[1442040837] 'applied index is now lower than readState.Index' (duration: 275.62828ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:35.494876Z","caller":"traceutil/trace.go:171","msg":"trace[645069550] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"829.251135ms","start":"2026-04-20T14:09:34.665597Z","end":"2026-04-20T14:09:35.494848Z","steps":["trace[645069550] 'process raft request' (duration: 553.442481ms)","trace[645069550] 'compare' (duration: 275.413665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:35.49503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:34.665578Z","time spent":"829.374938ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:09:35.495502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.557385ms","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-20T14:09:35.495561Z","caller":"traceutil/trace.go:171","msg":"trace[2140344479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"581.653957ms","start":"2026-04-20T14:09:34.913883Z","end":"2026-04-20T14:09:35.495537Z","steps":["trace[2140344479] 'agreement among raft nodes before linearized reading' (duration: 581.369171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:35.52397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:34.913858Z","time spent":"610.101754ms","remote":"127.0.0.1:50122","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-20T14:09:35.6192Z","caller":"traceutil/trace.go:171","msg":"trace[1311486109] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"441.314642ms","start":"2026-04-20T14:09:35.177845Z","end":"2026-04-20T14:09:35.61916Z","steps":["trace[1311486109] 'process raft request' (duration: 441.016976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:35.619338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:35.177816Z","time spent":"441.457776ms","remote":"127.0.0.1:50102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:09:35.619343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.21867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:35.619454Z","caller":"traceutil/trace.go:171","msg":"trace[1562730503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"531.361913ms","start":"2026-04-20T14:09:35.088068Z","end":"2026-04-20T14:09:35.61943Z","steps":["trace[1562730503] 'agreement among raft nodes before linearized reading' (duration: 531.192829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:35.619525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:35.088055Z","time spent":"531.451936ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:09:35.619633Z","caller":"traceutil/trace.go:171","msg":"trace[308721652] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"124.259042ms","start":"2026-04-20T14:09:35.494826Z","end":"2026-04-20T14:09:35.619085Z","steps":["trace[308721652] 'read index received' (duration: 123.949865ms)","trace[308721652] 'applied index is now lower than readState.Index' (duration: 307.847µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:35.630715Z","caller":"traceutil/trace.go:171","msg":"trace[1463567700] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"104.255964ms","start":"2026-04-20T14:09:35.526435Z","end":"2026-04-20T14:09:35.630691Z","steps":["trace[1463567700] 'process raft request' (duration: 104.037819ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:10:10.429937Z","caller":"traceutil/trace.go:171","msg":"trace[1948287145] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"127.080128ms","start":"2026-04-20T14:10:10.302811Z","end":"2026-04-20T14:10:10.429892Z","steps":["trace[1948287145] 'read index received' (duration: 102.844554ms)","trace[1948287145] 'applied index is now lower than readState.Index' (duration: 24.233414ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:10:10.430031Z","caller":"traceutil/trace.go:171","msg":"trace[2034915357] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"157.17424ms","start":"2026-04-20T14:10:10.272807Z","end":"2026-04-20T14:10:10.429981Z","steps":["trace[2034915357] 'process raft request' (duration: 132.920796ms)","trace[2034915357] 'compare' (duration: 23.706483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:10:10.430198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.374304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f00951d3-fc1b-4969-8778-3e276dbe14e4\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-20T14:10:10.430291Z","caller":"traceutil/trace.go:171","msg":"trace[842637740] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f00951d3-fc1b-4969-8778-3e276dbe14e4; range_end:; response_count:1; response_revision:1011; }","duration":"127.513276ms","start":"2026-04-20T14:10:10.302758Z","end":"2026-04-20T14:10:10.430271Z","steps":["trace[842637740] 'agreement among raft nodes before linearized reading' (duration: 127.252691ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:10:25.098595Z","caller":"traceutil/trace.go:171","msg":"trace[1633848497] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"173.799613ms","start":"2026-04-20T14:10:24.92477Z","end":"2026-04-20T14:10:25.098569Z","steps":["trace[1633848497] 'process raft request' (duration: 173.64889ms)"],"step_count":1}