{"level":"warn","ts":"2026-04-07T00:11:06.634814Z","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-07T00:11:06.634917Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-07T00:11:06.635007Z","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-07T00:11:06.635024Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-04-07T00:11:06.635049Z","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-07T00:11:06.636062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-04-07T00:11:06.636168Z","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.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-07T00:11:06.743297Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"106.745509ms"} {"level":"info","ts":"2026-04-07T00:11:06.775892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-04-07T00:11:06.776034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:11:06.776101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:11:06.776125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:11:06.77618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:11:06.776273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-04-07T00:11:06.789226Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:11:06.798205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:11:06.800214Z","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-07T00:11:06.804221Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:11:06.804335Z","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-07T00:11:06.804419Z","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-07T00:11:06.804439Z","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-07T00:11:06.804383Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:11:06.810376Z","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-07T00:11:06.810561Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:11:06.810592Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:11:06.810546Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-04-07T00:11:06.810846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-04-07T00:11:06.811431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-04-07T00:11:06.811668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-04-07T00:11:07.577549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:11:07.577649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:11:07.577715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-04-07T00:11:07.577748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:11:07.577766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-04-07T00:11:07.577791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:11:07.577805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-04-07T00:11:07.585237Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:11:07.595119Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:11:07.595274Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:11:07.595292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:11:07.595599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:11:07.595652Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:11:07.596987Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:11:07.59872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-04-07T00:11:07.606949Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:11:07.607301Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:11:07.607473Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-07T00:11:09.588467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.795548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T00:11:09.588771Z","caller":"traceutil/trace.go:171","msg":"trace[1327321682] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"373.391699ms","start":"2026-04-07T00:11:09.21534Z","end":"2026-04-07T00:11:09.588732Z","steps":["trace[1327321682] 'process raft request' (duration: 142.626809ms)","trace[1327321682] 'compare' (duration: 229.537703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:09.588991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:09.215316Z","time spent":"373.589312ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:11:09.589041Z","caller":"traceutil/trace.go:171","msg":"trace[531268476] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"371.663111ms","start":"2026-04-07T00:11:09.217327Z","end":"2026-04-07T00:11:09.58899Z","steps":["trace[531268476] 'process raft request' (duration: 371.329474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:09.588768Z","caller":"traceutil/trace.go:171","msg":"trace[1307014622] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:52; }","duration":"265.352531ms","start":"2026-04-07T00:11:09.323389Z","end":"2026-04-07T00:11:09.588742Z","steps":["trace[1307014622] 'read index received' (duration: 34.603672ms)","trace[1307014622] 'applied index is now lower than readState.Index' (duration: 230.747629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:09.589301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.8ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:11:09.589464Z","caller":"traceutil/trace.go:171","msg":"trace[673400114] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:51; }","duration":"266.102197ms","start":"2026-04-07T00:11:09.323326Z","end":"2026-04-07T00:11:09.589428Z","steps":["trace[673400114] 'agreement among raft nodes before linearized reading' (duration: 265.724919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:09.589658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:09.217302Z","time spent":"371.857966ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:09.937145Z","caller":"traceutil/trace.go:171","msg":"trace[857338764] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"341.858841ms","start":"2026-04-07T00:11:09.595258Z","end":"2026-04-07T00:11:09.937117Z","steps":["trace[857338764] 'process raft request' (duration: 250.669085ms)","trace[857338764] 'compare' (duration: 90.963191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:09.937434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:09.595237Z","time spent":"342.079256ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:09.937549Z","caller":"traceutil/trace.go:171","msg":"trace[538643665] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"340.095753ms","start":"2026-04-07T00:11:09.597447Z","end":"2026-04-07T00:11:09.937542Z","steps":["trace[538643665] 'process raft request' (duration: 339.559622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:09.937475Z","caller":"traceutil/trace.go:171","msg":"trace[1552154873] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"272.505804ms","start":"2026-04-07T00:11:09.664925Z","end":"2026-04-07T00:11:09.937431Z","steps":["trace[1552154873] 'process raft request' (duration: 272.352521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:09.93765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:09.597432Z","time spent":"340.167255ms","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:11:10.445563Z","caller":"traceutil/trace.go:171","msg":"trace[708316091] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"102.11454ms","start":"2026-04-07T00:11:10.343396Z","end":"2026-04-07T00:11:10.44551Z","steps":["trace[708316091] 'process raft request' (duration: 53.845215ms)","trace[708316091] 'compare' (duration: 47.752054ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:11.99173Z","caller":"traceutil/trace.go:171","msg":"trace[1188572784] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"205.398275ms","start":"2026-04-07T00:11:11.786299Z","end":"2026-04-07T00:11:11.991697Z","steps":["trace[1188572784] 'process raft request' (duration: 205.273752ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821112Z","caller":"traceutil/trace.go:171","msg":"trace[1827274114] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"225.864091ms","start":"2026-04-07T00:11:37.595185Z","end":"2026-04-07T00:11:37.821049Z","steps":["trace[1827274114] 'process raft request' (duration: 225.730549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821128Z","caller":"traceutil/trace.go:171","msg":"trace[1119410873] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"226.130227ms","start":"2026-04-07T00:11:37.59491Z","end":"2026-04-07T00:11:37.821041Z","steps":["trace[1119410873] 'process raft request' (duration: 162.866883ms)","trace[1119410873] 'compare' (duration: 62.973478ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:37.821513Z","caller":"traceutil/trace.go:171","msg":"trace[1252850601] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"216.360401ms","start":"2026-04-07T00:11:37.605136Z","end":"2026-04-07T00:11:37.821496Z","steps":["trace[1252850601] 'process raft request' (duration: 215.85104ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821734Z","caller":"traceutil/trace.go:171","msg":"trace[676327006] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"214.663756ms","start":"2026-04-07T00:11:37.607049Z","end":"2026-04-07T00:11:37.821713Z","steps":["trace[676327006] 'process raft request' (duration: 214.477492ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821785Z","caller":"traceutil/trace.go:171","msg":"trace[1939461546] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"216.398442ms","start":"2026-04-07T00:11:37.605216Z","end":"2026-04-07T00:11:37.821614Z","steps":["trace[1939461546] 'process raft request' (duration: 216.158017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821903Z","caller":"traceutil/trace.go:171","msg":"trace[34101416] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"214.655085ms","start":"2026-04-07T00:11:37.607216Z","end":"2026-04-07T00:11:37.821871Z","steps":["trace[34101416] 'process raft request' (duration: 214.364929ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821978Z","caller":"traceutil/trace.go:171","msg":"trace[442221126] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"212.49177ms","start":"2026-04-07T00:11:37.609478Z","end":"2026-04-07T00:11:37.82197Z","steps":["trace[442221126] 'process raft request' (duration: 212.157373ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:37.821938Z","caller":"traceutil/trace.go:171","msg":"trace[1449800770] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"131.096104ms","start":"2026-04-07T00:11:37.690826Z","end":"2026-04-07T00:11:37.821922Z","steps":["trace[1449800770] 'process raft request' (duration: 130.94657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:44.191813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.774148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:44.192157Z","caller":"traceutil/trace.go:171","msg":"trace[402161703] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"248.900888ms","start":"2026-04-07T00:11:43.94324Z","end":"2026-04-07T00:11:44.192141Z","steps":["trace[402161703] 'compare' (duration: 242.685157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:44.681406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.95751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:44.681532Z","caller":"traceutil/trace.go:171","msg":"trace[390137425] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"477.602659ms","start":"2026-04-07T00:11:44.203906Z","end":"2026-04-07T00:11:44.681509Z","steps":["trace[390137425] 'process raft request' (duration: 254.451915ms)","trace[390137425] 'compare' (duration: 222.785805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:44.681622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:44.203884Z","time spent":"477.69426ms","remote":"127.0.0.1:35092","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-04-07T00:11:44.701001Z","caller":"traceutil/trace.go:171","msg":"trace[685072753] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"310.696361ms","start":"2026-04-07T00:11:44.390267Z","end":"2026-04-07T00:11:44.700963Z","steps":["trace[685072753] 'read index received' (duration: 68.170157ms)","trace[685072753] 'applied index is now lower than readState.Index' (duration: 242.524973ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:44.701052Z","caller":"traceutil/trace.go:171","msg":"trace[483981909] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"386.780144ms","start":"2026-04-07T00:11:44.314248Z","end":"2026-04-07T00:11:44.701028Z","steps":["trace[483981909] 'process raft request' (duration: 386.220181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:44.701166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:44.314216Z","time spent":"386.901877ms","remote":"127.0.0.1:35012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:44.701194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.909305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:44.70124Z","caller":"traceutil/trace.go:171","msg":"trace[1573100215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"311.009867ms","start":"2026-04-07T00:11:44.390214Z","end":"2026-04-07T00:11:44.701224Z","steps":["trace[1573100215] 'agreement among raft nodes before linearized reading' (duration: 310.922675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:44.70128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:44.390193Z","time spent":"311.079658ms","remote":"127.0.0.1:34916","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-04-07T00:11:59.618989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.597493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:59.619211Z","caller":"traceutil/trace.go:171","msg":"trace[336797360] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"229.59127ms","start":"2026-04-07T00:11:59.389599Z","end":"2026-04-07T00:11:59.61919Z","steps":["trace[336797360] 'read index received' (duration: 61.312463ms)","trace[336797360] 'applied index is now lower than readState.Index' (duration: 168.277667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:59.619342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.737823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:59.619391Z","caller":"traceutil/trace.go:171","msg":"trace[893248981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"229.821795ms","start":"2026-04-07T00:11:59.389555Z","end":"2026-04-07T00:11:59.619377Z","steps":["trace[893248981] 'agreement among raft nodes before linearized reading' (duration: 229.725463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:59.619625Z","caller":"traceutil/trace.go:171","msg":"trace[209871193] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"272.439503ms","start":"2026-04-07T00:11:59.347171Z","end":"2026-04-07T00:11:59.619611Z","steps":["trace[209871193] 'process raft request' (duration: 271.947443ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:59.619759Z","caller":"traceutil/trace.go:171","msg":"trace[1651384511] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"273.580878ms","start":"2026-04-07T00:11:59.34612Z","end":"2026-04-07T00:11:59.619701Z","steps":["trace[1651384511] 'process raft request' (duration: 104.781ms)","trace[1651384511] 'compare' (duration: 167.45303ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:33.897972Z","caller":"traceutil/trace.go:171","msg":"trace[872282937] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"128.549211ms","start":"2026-04-07T00:12:33.769398Z","end":"2026-04-07T00:12:33.897948Z","steps":["trace[872282937] 'process raft request' (duration: 128.419278ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.288844Z","caller":"traceutil/trace.go:171","msg":"trace[1745407942] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"515.513589ms","start":"2026-04-07T00:12:33.773305Z","end":"2026-04-07T00:12:34.288818Z","steps":["trace[1745407942] 'process raft request' (duration: 515.367216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.288988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:33.773293Z","time spent":"515.621271ms","remote":"127.0.0.1:35190","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-04-07T00:12:34.307253Z","caller":"traceutil/trace.go:171","msg":"trace[409642671] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"192.81716ms","start":"2026-04-07T00:12:34.114406Z","end":"2026-04-07T00:12:34.307223Z","steps":["trace[409642671] 'process raft request' (duration: 192.680407ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:55.637556Z","caller":"traceutil/trace.go:171","msg":"trace[135058373] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"104.974476ms","start":"2026-04-07T00:12:55.532567Z","end":"2026-04-07T00:12:55.637541Z","steps":["trace[135058373] 'process raft request' (duration: 104.872444ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:25.779853Z","caller":"traceutil/trace.go:171","msg":"trace[1683782608] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"186.832755ms","start":"2026-04-07T00:13:25.592973Z","end":"2026-04-07T00:13:25.779806Z","steps":["trace[1683782608] 'process raft request' (duration: 186.697361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:25.954373Z","caller":"traceutil/trace.go:171","msg":"trace[1314718494] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"167.5694ms","start":"2026-04-07T00:13:25.786781Z","end":"2026-04-07T00:13:25.954351Z","steps":["trace[1314718494] 'process raft request' (duration: 167.429547ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:25.989089Z","caller":"traceutil/trace.go:171","msg":"trace[773850488] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"201.894122ms","start":"2026-04-07T00:13:25.787143Z","end":"2026-04-07T00:13:25.989038Z","steps":["trace[773850488] 'process raft request' (duration: 201.720708ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:25.989324Z","caller":"traceutil/trace.go:171","msg":"trace[185263207] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"194.369673ms","start":"2026-04-07T00:13:25.794889Z","end":"2026-04-07T00:13:25.989259Z","steps":["trace[185263207] 'process raft request' (duration: 194.101668ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.515746Z","caller":"traceutil/trace.go:171","msg":"trace[804116220] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"171.577824ms","start":"2026-04-07T00:13:26.344148Z","end":"2026-04-07T00:13:26.515726Z","steps":["trace[804116220] 'process raft request' (duration: 123.449063ms)","trace[804116220] 'compare' (duration: 47.888636ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:26.515883Z","caller":"traceutil/trace.go:171","msg":"trace[1719149918] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"163.651438ms","start":"2026-04-07T00:13:26.352218Z","end":"2026-04-07T00:13:26.515869Z","steps":["trace[1719149918] 'read index received' (duration: 115.389364ms)","trace[1719149918] 'applied index is now lower than readState.Index' (duration: 48.261084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:26.51606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.810921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:13:26.516103Z","caller":"traceutil/trace.go:171","msg":"trace[1125070998] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1244; }","duration":"163.962564ms","start":"2026-04-07T00:13:26.352131Z","end":"2026-04-07T00:13:26.516093Z","steps":["trace[1125070998] 'agreement among raft nodes before linearized reading' (duration: 163.829331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.731106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.135535ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:13:26.731177Z","caller":"traceutil/trace.go:171","msg":"trace[1102783642] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"215.228782ms","start":"2026-04-07T00:13:26.515936Z","end":"2026-04-07T00:13:26.731165Z","steps":["trace[1102783642] 'read index received' (duration: 3.992445ms)","trace[1102783642] 'applied index is now lower than readState.Index' (duration: 211.235127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:26.731259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.940643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:13:26.731278Z","caller":"traceutil/trace.go:171","msg":"trace[1421677006] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1244; }","duration":"321.985864ms","start":"2026-04-07T00:13:26.409285Z","end":"2026-04-07T00:13:26.731271Z","steps":["trace[1421677006] 'agreement among raft nodes before linearized reading' (duration: 321.933853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.73128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.580704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:13:26.731299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.409274Z","time spent":"322.019465ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:13:26.731331Z","caller":"traceutil/trace.go:171","msg":"trace[1996187011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"340.667486ms","start":"2026-04-07T00:13:26.39065Z","end":"2026-04-07T00:13:26.731318Z","steps":["trace[1996187011] 'agreement among raft nodes before linearized reading' (duration: 340.592524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.731363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:13:26.390632Z","time spent":"340.724877ms","remote":"127.0.0.1:34916","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-07T00:13:26.734389Z","caller":"traceutil/trace.go:171","msg":"trace[1048644869] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"214.025445ms","start":"2026-04-07T00:13:26.520354Z","end":"2026-04-07T00:13:26.734379Z","steps":["trace[1048644869] 'process raft request' (duration: 213.949363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.757974Z","caller":"traceutil/trace.go:171","msg":"trace[1398628952] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"235.528778ms","start":"2026-04-07T00:13:26.522427Z","end":"2026-04-07T00:13:26.757956Z","steps":["trace[1398628952] 'process raft request' (duration: 235.344804ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:26.757992Z","caller":"traceutil/trace.go:171","msg":"trace[284004031] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"234.386354ms","start":"2026-04-07T00:13:26.523581Z","end":"2026-04-07T00:13:26.757968Z","steps":["trace[284004031] 'process raft request' (duration: 234.313153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.758169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.828107ms","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-07T00:13:26.758225Z","caller":"traceutil/trace.go:171","msg":"trace[603303551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"138.922348ms","start":"2026-04-07T00:13:26.61929Z","end":"2026-04-07T00:13:26.758212Z","steps":["trace[603303551] 'agreement among raft nodes before linearized reading' (duration: 138.782845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:26.758339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.588024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T00:13:26.758397Z","caller":"traceutil/trace.go:171","msg":"trace[817441899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"131.660546ms","start":"2026-04-07T00:13:26.626724Z","end":"2026-04-07T00:13:26.758384Z","steps":["trace[817441899] 'agreement among raft nodes before linearized reading' (duration: 131.466992ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:27.331346Z","caller":"traceutil/trace.go:171","msg":"trace[562557676] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"134.631328ms","start":"2026-04-07T00:13:27.196697Z","end":"2026-04-07T00:13:27.331329Z","steps":["trace[562557676] 'process raft request' (duration: 134.513326ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:27.490684Z","caller":"traceutil/trace.go:171","msg":"trace[194856920] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"132.230017ms","start":"2026-04-07T00:13:27.358428Z","end":"2026-04-07T00:13:27.490658Z","steps":["trace[194856920] 'process raft request' (duration: 129.99759ms)"],"step_count":1}