{"level":"warn","ts":"2026-06-03T21:42:07.795345Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:42:07.795418Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-06-03T21:42:07.795478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:42:07.795485Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-03T21:42:07.795498Z","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-06-03T21:42:07.796114Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-06-03T21:42:07.79618Z","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.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-06-03T21:42:07.808307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.812012ms"} {"level":"info","ts":"2026-06-03T21:42:07.831418Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-06-03T21:42:07.831563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T21:42:07.831617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-06-03T21:42:07.831649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T21:42:07.831675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-06-03T21:42:07.831742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-06-03T21:42:07.848461Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T21:42:07.851768Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T21:42:07.853097Z","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-06-03T21:42:07.856718Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T21:42:07.856938Z","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-06-03T21:42:07.857035Z","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-06-03T21:42:07.857051Z","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-06-03T21:42:07.857405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T21:42:07.859679Z","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-06-03T21:42:07.859916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-06-03T21:42:07.860034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-03T21:42:07.860327Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-03T21:42:07.860338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-03T21:42:07.860463Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T21:42:07.860562Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T21:42:08.533412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T21:42:08.533483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T21:42:08.533721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-06-03T21:42:08.533811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-06-03T21:42:08.533832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-03T21:42:08.533934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-06-03T21:42:08.534025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-03T21:42:08.535299Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:42:08.535673Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T21:42:08.535691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:42:08.535667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:42:08.536009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:42:08.536109Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:42:08.536151Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:42:08.537521Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T21:42:08.539266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-06-03T21:42:08.545015Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T21:42:08.545056Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-06-03T21:42:11.454506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.793003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:11.45484Z","caller":"traceutil/trace.go:171","msg":"trace[630136805] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"644.518927ms","start":"2026-06-03T21:42:10.810268Z","end":"2026-06-03T21:42:11.454787Z","steps":["trace[630136805] 'read index received' (duration: 122.959915ms)","trace[630136805] 'applied index is now lower than readState.Index' (duration: 521.555972ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:11.454966Z","caller":"traceutil/trace.go:171","msg":"trace[1333614840] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"645.290224ms","start":"2026-06-03T21:42:10.809659Z","end":"2026-06-03T21:42:11.454949Z","steps":["trace[1333614840] 'process raft request' (duration: 123.595342ms)","trace[1333614840] 'compare' (duration: 519.628321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:11.455052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:10.809635Z","time spent":"645.375965ms","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:11.455596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.308994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:11.455657Z","caller":"traceutil/trace.go:171","msg":"trace[1545756095] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"645.382655ms","start":"2026-06-03T21:42:10.810244Z","end":"2026-06-03T21:42:11.455627Z","steps":["trace[1545756095] 'agreement among raft nodes before linearized reading' (duration: 645.151913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:11.455711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:10.810231Z","time spent":"645.467267ms","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-06-03T21:42:12.819383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192113763s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:12.819637Z","caller":"traceutil/trace.go:171","msg":"trace[1912150228] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"1.364712216s","start":"2026-06-03T21:42:11.454898Z","end":"2026-06-03T21:42:12.81961Z","steps":["trace[1912150228] 'read index received' (duration: 172.3844ms)","trace[1912150228] 'applied index is now lower than readState.Index' (duration: 1.192326536s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:12.819645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:11.36257Z","time spent":"1.457065633s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-03T21:42:12.819663Z","caller":"traceutil/trace.go:171","msg":"trace[1380688264] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.609431252s","start":"2026-06-03T21:42:11.210177Z","end":"2026-06-03T21:42:12.819609Z","steps":["trace[1380688264] 'process raft request' (duration: 417.023886ms)","trace[1380688264] 'compare' (duration: 1.191969182s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:12.819794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.978780555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:12.819836Z","caller":"traceutil/trace.go:171","msg":"trace[1389928187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"1.978896356s","start":"2026-06-03T21:42:10.840926Z","end":"2026-06-03T21:42:12.819822Z","steps":["trace[1389928187] 'agreement among raft nodes before linearized reading' (duration: 1.978829406s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:12.819831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:11.21015Z","time spent":"1.609606674s","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:12.819877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:10.840905Z","time spent":"1.978963987s","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T21:42:12.820108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361003189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:12.820165Z","caller":"traceutil/trace.go:171","msg":"trace[230280848] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:74; }","duration":"1.361090669s","start":"2026-06-03T21:42:11.459055Z","end":"2026-06-03T21:42:12.820146Z","steps":["trace[230280848] 'agreement among raft nodes before linearized reading' (duration: 1.361001778s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:12.820204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:11.459038Z","time spent":"1.361159271s","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-06-03T21:42:12.820267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.993924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-03T21:42:12.820272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.305372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:12.820317Z","caller":"traceutil/trace.go:171","msg":"trace[89598522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:74; }","duration":"983.072325ms","start":"2026-06-03T21:42:11.837226Z","end":"2026-06-03T21:42:12.820298Z","steps":["trace[89598522] 'agreement among raft nodes before linearized reading' (duration: 983.015734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:12.820367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:11.837196Z","time spent":"983.163886ms","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T21:42:12.820315Z","caller":"traceutil/trace.go:171","msg":"trace[354574913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"836.376073ms","start":"2026-06-03T21:42:11.983926Z","end":"2026-06-03T21:42:12.820302Z","steps":["trace[354574913] 'agreement among raft nodes before linearized reading' (duration: 836.299402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:12.820413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:11.983904Z","time spent":"836.502324ms","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T21:42:13.323388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970927143803363,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:42:13.823594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970927143803363,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:42:14.324376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970927143803363,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:42:14.655403Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.833488221s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-03T21:42:15.041669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000241006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-03T21:42:15.041803Z","caller":"traceutil/trace.go:171","msg":"trace[7977234] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000432447s","start":"2026-06-03T21:42:13.041352Z","end":"2026-06-03T21:42:15.041784Z","steps":["trace[7977234] 'agreement among raft nodes before linearized reading' (duration: 2.000267556s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:15.041871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:13.041331Z","time spent":"2.000529628s","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/03 21:42:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-03T21:42:15.548925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.185475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:15.549093Z","caller":"traceutil/trace.go:171","msg":"trace[1850534051] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"2.727237812s","start":"2026-06-03T21:42:12.821828Z","end":"2026-06-03T21:42:15.549066Z","steps":["trace[1850534051] 'process raft request' (duration: 1.833805144s)","trace[1850534051] 'compare' (duration: 893.077574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:15.549175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:12.82179Z","time spent":"2.727350143s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:15.984432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328734052s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-03T21:42:16.485116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00069748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-03T21:42:16.485174Z","caller":"traceutil/trace.go:171","msg":"trace[11808831] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000826382s","start":"2026-06-03T21:42:14.484334Z","end":"2026-06-03T21:42:16.485161Z","steps":["trace[11808831] 'agreement among raft nodes before linearized reading' (duration: 2.000727401s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:16.485203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:14.484311Z","time spent":"2.000886083s","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/03 21:42:16 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-03T21:42:16.649764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.880433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:16.649917Z","caller":"traceutil/trace.go:171","msg":"trace[1048430827] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"1.096146078s","start":"2026-06-03T21:42:15.553754Z","end":"2026-06-03T21:42:16.6499Z","steps":["trace[1048430827] 'process raft request' (duration: 1.096100407s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:16.64998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:15.553734Z","time spent":"1.096222199s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:42:16.650213Z","caller":"traceutil/trace.go:171","msg":"trace[1437866889] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"3.826083455s","start":"2026-06-03T21:42:12.824045Z","end":"2026-06-03T21:42:16.650129Z","steps":["trace[1437866889] 'process raft request' (duration: 3.160763087s)","trace[1437866889] 'compare' (duration: 664.758722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:16.65046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.827182637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:16.650507Z","caller":"traceutil/trace.go:171","msg":"trace[1436152026] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:77; }","duration":"3.827251257s","start":"2026-06-03T21:42:12.823244Z","end":"2026-06-03T21:42:16.650495Z","steps":["trace[1436152026] 'agreement among raft nodes before linearized reading' (duration: 3.827123346s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:16.650545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:12.823232Z","time spent":"3.827306198s","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-06-03T21:42:16.650791Z","caller":"traceutil/trace.go:171","msg":"trace[1736202552] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:78; }","duration":"3.826916524s","start":"2026-06-03T21:42:12.823266Z","end":"2026-06-03T21:42:16.650182Z","steps":["trace[1736202552] 'read index received' (duration: 1.832513741s)","trace[1736202552] 'applied index is now lower than readState.Index' (duration: 1.994398473s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:16.651153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.098510072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-03T21:42:16.651365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609283447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:16.651444Z","caller":"traceutil/trace.go:171","msg":"trace[1177046800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"1.609396438s","start":"2026-06-03T21:42:15.04203Z","end":"2026-06-03T21:42:16.651427Z","steps":["trace[1177046800] 'agreement among raft nodes before linearized reading' (duration: 1.609273047s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:16.651466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:15.042013Z","time spent":"1.609447809s","remote":"127.0.0.1:39350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T21:42:16.651202Z","caller":"traceutil/trace.go:171","msg":"trace[776617382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:77; }","duration":"3.098587933s","start":"2026-06-03T21:42:13.5526Z","end":"2026-06-03T21:42:16.651188Z","steps":["trace[776617382] 'agreement among raft nodes before linearized reading' (duration: 3.098504072s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:16.6516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:13.552558Z","time spent":"3.098968366s","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T21:42:16.651779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:12.824021Z","time spent":"3.826413379s","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:17.477181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.837734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:17.477287Z","caller":"traceutil/trace.go:171","msg":"trace[504408755] linearizableReadLoop","detail":"{readStateIndex:99; appliedIndex:98; }","duration":"460.740482ms","start":"2026-06-03T21:42:17.01653Z","end":"2026-06-03T21:42:17.477271Z","steps":["trace[504408755] 'read index received' (duration: 205.566725ms)","trace[504408755] 'applied index is now lower than readState.Index' (duration: 255.172217ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:17.477418Z","caller":"traceutil/trace.go:171","msg":"trace[439134714] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"508.455131ms","start":"2026-06-03T21:42:16.968944Z","end":"2026-06-03T21:42:17.477399Z","steps":["trace[439134714] 'process raft request' (duration: 253.161633ms)","trace[439134714] 'compare' (duration: 254.726393ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:17.477538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:16.968928Z","time spent":"508.559392ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:42:17.477599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.037175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:17.477696Z","caller":"traceutil/trace.go:171","msg":"trace[568514611] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:95; }","duration":"461.197987ms","start":"2026-06-03T21:42:17.016475Z","end":"2026-06-03T21:42:17.477673Z","steps":["trace[568514611] 'agreement among raft nodes before linearized reading' (duration: 460.968415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:17.477774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.016455Z","time spent":"461.301548ms","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-06-03T21:42:17.477858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.290047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2917"} {"level":"info","ts":"2026-06-03T21:42:17.477943Z","caller":"traceutil/trace.go:171","msg":"trace[1182438312] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:95; }","duration":"460.434298ms","start":"2026-06-03T21:42:17.017493Z","end":"2026-06-03T21:42:17.477927Z","steps":["trace[1182438312] 'agreement among raft nodes before linearized reading' (duration: 459.920963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:17.478041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.017485Z","time spent":"460.540569ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2939,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-06-03T21:42:17.975236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.211412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:17.975473Z","caller":"traceutil/trace.go:171","msg":"trace[995322386] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"489.626798ms","start":"2026-06-03T21:42:17.485806Z","end":"2026-06-03T21:42:17.975433Z","steps":["trace[995322386] 'process raft request' (duration: 153.127754ms)","trace[995322386] 'compare' (duration: 336.094601ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:17.975534Z","caller":"traceutil/trace.go:171","msg":"trace[1356143255] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:100; }","duration":"245.840289ms","start":"2026-06-03T21:42:17.729677Z","end":"2026-06-03T21:42:17.975517Z","steps":["trace[1356143255] 'read index received' (duration: 245.427455ms)","trace[1356143255] 'applied index is now lower than readState.Index' (duration: 411.574µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:17.975653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.48579Z","time spent":"489.76928ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:42:17.975851Z","caller":"traceutil/trace.go:171","msg":"trace[839241473] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"488.240524ms","start":"2026-06-03T21:42:17.487584Z","end":"2026-06-03T21:42:17.975825Z","steps":["trace[839241473] 'process raft request' (duration: 487.76081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:17.975868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.216532ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:17.975976Z","caller":"traceutil/trace.go:171","msg":"trace[416244260] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:99; }","duration":"246.323843ms","start":"2026-06-03T21:42:17.729634Z","end":"2026-06-03T21:42:17.975957Z","steps":["trace[416244260] 'agreement among raft nodes before linearized reading' (duration: 246.125971ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:17.975869Z","caller":"traceutil/trace.go:171","msg":"trace[1512701984] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"476.869595ms","start":"2026-06-03T21:42:17.498964Z","end":"2026-06-03T21:42:17.975833Z","steps":["trace[1512701984] 'process raft request' (duration: 476.475681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:17.975993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.487575Z","time spent":"488.351025ms","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":708,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:17.976113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.498952Z","time spent":"477.081097ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:42:18.362935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.56184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:18.363061Z","caller":"traceutil/trace.go:171","msg":"trace[596715908] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"387.470315ms","start":"2026-06-03T21:42:17.975569Z","end":"2026-06-03T21:42:18.36304Z","steps":["trace[596715908] 'read index received' (duration: 240.764855ms)","trace[596715908] 'applied index is now lower than readState.Index' (duration: 146.70395ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:18.363088Z","caller":"traceutil/trace.go:171","msg":"trace[1338064384] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"629.092954ms","start":"2026-06-03T21:42:17.733968Z","end":"2026-06-03T21:42:18.363061Z","steps":["trace[1338064384] 'process raft request' (duration: 482.334123ms)","trace[1338064384] 'compare' (duration: 146.409358ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:18.363179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.733941Z","time spent":"629.199666ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:18.363251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.89735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-03T21:42:18.363266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.264985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:18.363324Z","caller":"traceutil/trace.go:171","msg":"trace[166679562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:100; }","duration":"523.026551ms","start":"2026-06-03T21:42:17.840255Z","end":"2026-06-03T21:42:18.363282Z","steps":["trace[166679562] 'agreement among raft nodes before linearized reading' (duration: 522.87324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:18.363373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.840231Z","time spent":"523.120992ms","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T21:42:18.363343Z","caller":"traceutil/trace.go:171","msg":"trace[1444132817] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:100; }","duration":"384.372616ms","start":"2026-06-03T21:42:17.978956Z","end":"2026-06-03T21:42:18.363329Z","steps":["trace[1444132817] 'agreement among raft nodes before linearized reading' (duration: 384.234275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:18.363357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.863783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"warn","ts":"2026-06-03T21:42:18.363416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.978937Z","time spent":"384.466127ms","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-06-03T21:42:18.36344Z","caller":"traceutil/trace.go:171","msg":"trace[24608076] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:100; }","duration":"382.984124ms","start":"2026-06-03T21:42:17.980437Z","end":"2026-06-03T21:42:18.363421Z","steps":["trace[24608076] 'agreement among raft nodes before linearized reading' (duration: 382.790612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:18.363486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.980428Z","time spent":"383.050014ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3487,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-06-03T21:42:18.363484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.618469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b5b02b1e956ab7\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-06-03T21:42:18.363559Z","caller":"traceutil/trace.go:171","msg":"trace[65490630] range","detail":"{range_begin:/registry/events/default/instance.18b5b02b1e956ab7; range_end:; response_count:1; response_revision:100; }","duration":"383.767431ms","start":"2026-06-03T21:42:17.979773Z","end":"2026-06-03T21:42:18.363541Z","steps":["trace[65490630] 'agreement among raft nodes before linearized reading' (duration: 383.599309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:18.363607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:17.979757Z","time spent":"383.841621ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":672,"request content":"key:\"/registry/events/default/instance.18b5b02b1e956ab7\" "} {"level":"warn","ts":"2026-06-03T21:42:18.622459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.659357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T21:42:18.622673Z","caller":"traceutil/trace.go:171","msg":"trace[1902435394] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"256.031688ms","start":"2026-06-03T21:42:18.366621Z","end":"2026-06-03T21:42:18.622653Z","steps":["trace[1902435394] 'process raft request' (duration: 102.087148ms)","trace[1902435394] 'compare' (duration: 153.462415ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:18.652241Z","caller":"traceutil/trace.go:171","msg":"trace[1742150218] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"279.108195ms","start":"2026-06-03T21:42:18.373097Z","end":"2026-06-03T21:42:18.652206Z","steps":["trace[1742150218] 'process raft request' (duration: 278.892363ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:18.652365Z","caller":"traceutil/trace.go:171","msg":"trace[1846678543] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"285.005492ms","start":"2026-06-03T21:42:18.367333Z","end":"2026-06-03T21:42:18.652339Z","steps":["trace[1846678543] 'process raft request' (duration: 284.573448ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:18.776622Z","caller":"traceutil/trace.go:171","msg":"trace[1870722978] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"118.236831ms","start":"2026-06-03T21:42:18.658354Z","end":"2026-06-03T21:42:18.776591Z","steps":["trace[1870722978] 'process raft request' (duration: 71.561228ms)","trace[1870722978] 'compare' (duration: 46.533662ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:18.92295Z","caller":"traceutil/trace.go:171","msg":"trace[468770380] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"128.281016ms","start":"2026-06-03T21:42:18.794648Z","end":"2026-06-03T21:42:18.922929Z","steps":["trace[468770380] 'read index received' (duration: 112.84258ms)","trace[468770380] 'applied index is now lower than readState.Index' (duration: 15.437486ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:18.923112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.446538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:18.923174Z","caller":"traceutil/trace.go:171","msg":"trace[135851866] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:0; response_revision:110; }","duration":"128.546579ms","start":"2026-06-03T21:42:18.794615Z","end":"2026-06-03T21:42:18.923161Z","steps":["trace[135851866] 'agreement among raft nodes before linearized reading' (duration: 128.430598ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:18.922978Z","caller":"traceutil/trace.go:171","msg":"trace[538977723] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"128.553709ms","start":"2026-06-03T21:42:18.794392Z","end":"2026-06-03T21:42:18.922946Z","steps":["trace[538977723] 'process raft request' (duration: 113.170102ms)","trace[538977723] 'compare' (duration: 15.265916ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:19.168328Z","caller":"traceutil/trace.go:171","msg":"trace[1072980401] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"191.429563ms","start":"2026-06-03T21:42:18.976875Z","end":"2026-06-03T21:42:19.168305Z","steps":["trace[1072980401] 'read index received' (duration: 151.84775ms)","trace[1072980401] 'applied index is now lower than readState.Index' (duration: 39.580623ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:19.168518Z","caller":"traceutil/trace.go:171","msg":"trace[1498505384] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"202.317157ms","start":"2026-06-03T21:42:18.96615Z","end":"2026-06-03T21:42:19.168467Z","steps":["trace[1498505384] 'process raft request' (duration: 162.681903ms)","trace[1498505384] 'compare' (duration: 39.336841ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:19.168749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.665353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5333"} {"level":"info","ts":"2026-06-03T21:42:19.168869Z","caller":"traceutil/trace.go:171","msg":"trace[217037982] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:114; }","duration":"108.819335ms","start":"2026-06-03T21:42:19.060015Z","end":"2026-06-03T21:42:19.168834Z","steps":["trace[217037982] 'agreement among raft nodes before linearized reading' (duration: 108.536702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:19.168768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.752607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:19.169044Z","caller":"traceutil/trace.go:171","msg":"trace[1041988825] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:0; response_revision:114; }","duration":"192.172821ms","start":"2026-06-03T21:42:18.976852Z","end":"2026-06-03T21:42:19.169025Z","steps":["trace[1041988825] 'agreement among raft nodes before linearized reading' (duration: 191.553035ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:19.334399Z","caller":"traceutil/trace.go:171","msg":"trace[36259150] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"141.259571ms","start":"2026-06-03T21:42:19.193117Z","end":"2026-06-03T21:42:19.334377Z","steps":["trace[36259150] 'process raft request' (duration: 141.17095ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:19.334511Z","caller":"traceutil/trace.go:171","msg":"trace[1143251219] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"141.365252ms","start":"2026-06-03T21:42:19.193104Z","end":"2026-06-03T21:42:19.334469Z","steps":["trace[1143251219] 'process raft request' (duration: 140.19437ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:19.549159Z","caller":"traceutil/trace.go:171","msg":"trace[1856993532] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:127; }","duration":"121.249941ms","start":"2026-06-03T21:42:19.427886Z","end":"2026-06-03T21:42:19.549136Z","steps":["trace[1856993532] 'read index received' (duration: 59.109526ms)","trace[1856993532] 'applied index is now lower than readState.Index' (duration: 62.139305ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:19.549284Z","caller":"traceutil/trace.go:171","msg":"trace[1776621398] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"121.567593ms","start":"2026-06-03T21:42:19.427681Z","end":"2026-06-03T21:42:19.549249Z","steps":["trace[1776621398] 'process raft request' (duration: 59.270077ms)","trace[1776621398] 'compare' (duration: 61.958673ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:19.549326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.424834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:19.549376Z","caller":"traceutil/trace.go:171","msg":"trace[1961020073] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:124; }","duration":"121.501334ms","start":"2026-06-03T21:42:19.427867Z","end":"2026-06-03T21:42:19.549368Z","steps":["trace[1961020073] 'agreement among raft nodes before linearized reading' (duration: 121.370332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:19.549778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.852454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T21:42:19.549875Z","caller":"traceutil/trace.go:171","msg":"trace[96491859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:124; }","duration":"109.973255ms","start":"2026-06-03T21:42:19.43988Z","end":"2026-06-03T21:42:19.549853Z","steps":["trace[96491859] 'agreement among raft nodes before linearized reading' (duration: 109.812814ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:19.904289Z","caller":"traceutil/trace.go:171","msg":"trace[2121376284] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"114.063733ms","start":"2026-06-03T21:42:19.790202Z","end":"2026-06-03T21:42:19.904266Z","steps":["trace[2121376284] 'process raft request' (duration: 114.007083ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:19.904491Z","caller":"traceutil/trace.go:171","msg":"trace[1361005341] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"114.425497ms","start":"2026-06-03T21:42:19.78997Z","end":"2026-06-03T21:42:19.904395Z","steps":["trace[1361005341] 'process raft request' (duration: 70.13189ms)","trace[1361005341] 'compare' (duration: 43.944953ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:21.410721Z","caller":"traceutil/trace.go:171","msg":"trace[795386463] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"117.296978ms","start":"2026-06-03T21:42:21.293399Z","end":"2026-06-03T21:42:21.410696Z","steps":["trace[795386463] 'read index received' (duration: 64.597979ms)","trace[795386463] 'applied index is now lower than readState.Index' (duration: 52.698009ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:21.41085Z","caller":"traceutil/trace.go:171","msg":"trace[1628203052] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"166.561735ms","start":"2026-06-03T21:42:21.244265Z","end":"2026-06-03T21:42:21.410827Z","steps":["trace[1628203052] 'process raft request' (duration: 113.709495ms)","trace[1628203052] 'compare' (duration: 52.628418ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:21.410942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.51084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:42:21.411022Z","caller":"traceutil/trace.go:171","msg":"trace[1612564518] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:230; }","duration":"117.635152ms","start":"2026-06-03T21:42:21.293361Z","end":"2026-06-03T21:42:21.410996Z","steps":["trace[1612564518] 'agreement among raft nodes before linearized reading' (duration: 117.503791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:21.633534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.76123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:42:21.633658Z","caller":"traceutil/trace.go:171","msg":"trace[246483490] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"145.889105ms","start":"2026-06-03T21:42:21.48775Z","end":"2026-06-03T21:42:21.633639Z","steps":["trace[246483490] 'process raft request' (duration: 37.878772ms)","trace[246483490] 'compare' (duration: 107.611868ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:21.639396Z","caller":"traceutil/trace.go:171","msg":"trace[472361107] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"150.045802ms","start":"2026-06-03T21:42:21.489331Z","end":"2026-06-03T21:42:21.639377Z","steps":["trace[472361107] 'process raft request' (duration: 149.724478ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:22.436377Z","caller":"traceutil/trace.go:171","msg":"trace[1937680953] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"140.147791ms","start":"2026-06-03T21:42:22.296075Z","end":"2026-06-03T21:42:22.436223Z","steps":["trace[1937680953] 'process raft request' (duration: 43.348369ms)","trace[1937680953] 'compare' (duration: 96.625431ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:22.65997Z","caller":"traceutil/trace.go:171","msg":"trace[1401545819] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"119.652473ms","start":"2026-06-03T21:42:22.540289Z","end":"2026-06-03T21:42:22.659941Z","steps":["trace[1401545819] 'read index received' (duration: 84.899953ms)","trace[1401545819] 'applied index is now lower than readState.Index' (duration: 34.75134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:22.66012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.798904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:42:22.660163Z","caller":"traceutil/trace.go:171","msg":"trace[844504785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:251; }","duration":"119.883835ms","start":"2026-06-03T21:42:22.540265Z","end":"2026-06-03T21:42:22.660149Z","steps":["trace[844504785] 'agreement among raft nodes before linearized reading' (duration: 119.775004ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:22.660153Z","caller":"traceutil/trace.go:171","msg":"trace[1380881345] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"168.384531ms","start":"2026-06-03T21:42:22.491733Z","end":"2026-06-03T21:42:22.660117Z","steps":["trace[1380881345] 'process raft request' (duration: 133.561381ms)","trace[1380881345] 'compare' (duration: 34.520148ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:23.693927Z","caller":"traceutil/trace.go:171","msg":"trace[837337522] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"101.318297ms","start":"2026-06-03T21:42:23.592578Z","end":"2026-06-03T21:42:23.693897Z","steps":["trace[837337522] 'process raft request' (duration: 63.875895ms)","trace[837337522] 'compare' (duration: 37.274461ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:26.931109Z","caller":"traceutil/trace.go:171","msg":"trace[4754873] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"113.192256ms","start":"2026-06-03T21:42:26.817872Z","end":"2026-06-03T21:42:26.931064Z","steps":["trace[4754873] 'process raft request' (duration: 112.856502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:27.100178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.345611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:42:27.100256Z","caller":"traceutil/trace.go:171","msg":"trace[1181533829] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:345; }","duration":"164.478412ms","start":"2026-06-03T21:42:26.935758Z","end":"2026-06-03T21:42:27.100236Z","steps":["trace[1181533829] 'range keys from in-memory index tree' (duration: 164.27075ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:27.100392Z","caller":"traceutil/trace.go:171","msg":"trace[355412868] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"164.192479ms","start":"2026-06-03T21:42:26.936175Z","end":"2026-06-03T21:42:27.100368Z","steps":["trace[355412868] 'process raft request' (duration: 164.056608ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:27.115076Z","caller":"traceutil/trace.go:171","msg":"trace[619427276] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"178.647788ms","start":"2026-06-03T21:42:26.93641Z","end":"2026-06-03T21:42:27.115058Z","steps":["trace[619427276] 'process raft request' (duration: 178.174774ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:27.115198Z","caller":"traceutil/trace.go:171","msg":"trace[595540581] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"178.702158ms","start":"2026-06-03T21:42:26.936489Z","end":"2026-06-03T21:42:27.115191Z","steps":["trace[595540581] 'process raft request' (duration: 178.217874ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:28.58851Z","caller":"traceutil/trace.go:171","msg":"trace[492729304] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"112.959432ms","start":"2026-06-03T21:42:28.475532Z","end":"2026-06-03T21:42:28.588492Z","steps":["trace[492729304] 'process raft request' (duration: 112.68918ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:29.248421Z","caller":"traceutil/trace.go:171","msg":"trace[46326393] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"118.911519ms","start":"2026-06-03T21:42:29.129485Z","end":"2026-06-03T21:42:29.248396Z","steps":["trace[46326393] 'process raft request' (duration: 118.832968ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:29.248431Z","caller":"traceutil/trace.go:171","msg":"trace[2027338629] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"119.187372ms","start":"2026-06-03T21:42:29.129208Z","end":"2026-06-03T21:42:29.248395Z","steps":["trace[2027338629] 'process raft request' (duration: 63.282569ms)","trace[2027338629] 'compare' (duration: 55.685931ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:42:29.248707Z","caller":"traceutil/trace.go:171","msg":"trace[1686912351] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"118.94704ms","start":"2026-06-03T21:42:29.129746Z","end":"2026-06-03T21:42:29.248694Z","steps":["trace[1686912351] 'process raft request' (duration: 118.611557ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:29.248914Z","caller":"traceutil/trace.go:171","msg":"trace[1172124254] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"118.717917ms","start":"2026-06-03T21:42:29.130178Z","end":"2026-06-03T21:42:29.248895Z","steps":["trace[1172124254] 'process raft request' (duration: 118.373264ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:29.248919Z","caller":"traceutil/trace.go:171","msg":"trace[1363749065] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"117.418736ms","start":"2026-06-03T21:42:29.131473Z","end":"2026-06-03T21:42:29.248891Z","steps":["trace[1363749065] 'process raft request' (duration: 117.355676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:29.807798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.838824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:42:29.807937Z","caller":"traceutil/trace.go:171","msg":"trace[1052459692] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"514.50205ms","start":"2026-06-03T21:42:29.293415Z","end":"2026-06-03T21:42:29.807917Z","steps":["trace[1052459692] 'process raft request' (duration: 298.462253ms)","trace[1052459692] 'compare' (duration: 215.713123ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:29.807998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:29.293392Z","time spent":"514.578951ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:42:30.14425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.663504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4325"} {"level":"info","ts":"2026-06-03T21:42:30.144312Z","caller":"traceutil/trace.go:171","msg":"trace[1773318591] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:394; }","duration":"116.779565ms","start":"2026-06-03T21:42:30.027518Z","end":"2026-06-03T21:42:30.144298Z","steps":["trace[1773318591] 'range keys from in-memory index tree' (duration: 116.557713ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.001089Z","caller":"traceutil/trace.go:171","msg":"trace[1335629168] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"300.73352ms","start":"2026-06-03T21:42:57.700332Z","end":"2026-06-03T21:42:58.001065Z","steps":["trace[1335629168] 'process raft request' (duration: 300.594885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:58.001235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:57.700313Z","time spent":"300.858285ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:42:58.013953Z","caller":"traceutil/trace.go:171","msg":"trace[1986359327] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"299.588084ms","start":"2026-06-03T21:42:57.714345Z","end":"2026-06-03T21:42:58.013933Z","steps":["trace[1986359327] 'process raft request' (duration: 299.427019ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014168Z","caller":"traceutil/trace.go:171","msg":"trace[1742315765] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:565; }","duration":"114.351607ms","start":"2026-06-03T21:42:57.899801Z","end":"2026-06-03T21:42:58.014153Z","steps":["trace[1742315765] 'read index received' (duration: 101.190238ms)","trace[1742315765] 'applied index is now lower than readState.Index' (duration: 13.160559ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:42:58.014266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.462721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:42:58.014288Z","caller":"traceutil/trace.go:171","msg":"trace[5583131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"114.537033ms","start":"2026-06-03T21:42:57.899744Z","end":"2026-06-03T21:42:58.014281Z","steps":["trace[5583131] 'agreement among raft nodes before linearized reading' (duration: 114.473471ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014395Z","caller":"traceutil/trace.go:171","msg":"trace[1005400824] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"297.048664ms","start":"2026-06-03T21:42:57.717333Z","end":"2026-06-03T21:42:58.014381Z","steps":["trace[1005400824] 'process raft request' (duration: 296.575718ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014437Z","caller":"traceutil/trace.go:171","msg":"trace[339515645] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"284.611944ms","start":"2026-06-03T21:42:57.729818Z","end":"2026-06-03T21:42:58.01443Z","steps":["trace[339515645] 'process raft request' (duration: 284.119847ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014479Z","caller":"traceutil/trace.go:171","msg":"trace[1296203945] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"307.813454ms","start":"2026-06-03T21:42:57.706439Z","end":"2026-06-03T21:42:58.014252Z","steps":["trace[1296203945] 'process raft request' (duration: 307.220694ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014557Z","caller":"traceutil/trace.go:171","msg":"trace[964168004] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"124.464754ms","start":"2026-06-03T21:42:57.890065Z","end":"2026-06-03T21:42:58.01453Z","steps":["trace[964168004] 'process raft request' (duration: 124.005158ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014576Z","caller":"traceutil/trace.go:171","msg":"trace[80321252] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"300.148253ms","start":"2026-06-03T21:42:57.714418Z","end":"2026-06-03T21:42:58.014567Z","steps":["trace[80321252] 'process raft request' (duration: 299.413008ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.01457Z","caller":"traceutil/trace.go:171","msg":"trace[1059506203] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"247.718561ms","start":"2026-06-03T21:42:57.766827Z","end":"2026-06-03T21:42:58.014545Z","steps":["trace[1059506203] 'process raft request' (duration: 247.192942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:58.014619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:57.714416Z","time spent":"300.177334ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:42:58.01468Z","caller":"traceutil/trace.go:171","msg":"trace[783180129] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"298.491775ms","start":"2026-06-03T21:42:57.716183Z","end":"2026-06-03T21:42:58.014675Z","steps":["trace[783180129] 'process raft request' (duration: 297.694197ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014512Z","caller":"traceutil/trace.go:171","msg":"trace[1459820378] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"272.973133ms","start":"2026-06-03T21:42:57.741529Z","end":"2026-06-03T21:42:58.014502Z","steps":["trace[1459820378] 'process raft request' (duration: 272.447695ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:42:58.014548Z","caller":"traceutil/trace.go:171","msg":"trace[269615325] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"300.154183ms","start":"2026-06-03T21:42:57.714386Z","end":"2026-06-03T21:42:58.01454Z","steps":["trace[269615325] 'process raft request' (duration: 299.413738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:58.01486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:57.714383Z","time spent":"300.432224ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:42:58.014753Z","caller":"traceutil/trace.go:171","msg":"trace[2071413018] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"307.851775ms","start":"2026-06-03T21:42:57.706667Z","end":"2026-06-03T21:42:58.014518Z","steps":["trace[2071413018] 'process raft request' (duration: 307.070998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:42:58.015138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:57.706662Z","time spent":"308.372144ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:42:58.014828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:42:57.706422Z","time spent":"308.162807ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:43:04.321456Z","caller":"traceutil/trace.go:171","msg":"trace[1005462287] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"422.963088ms","start":"2026-06-03T21:43:03.898472Z","end":"2026-06-03T21:43:04.321435Z","steps":["trace[1005462287] 'process raft request' (duration: 421.999535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:04.321582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.510497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:43:04.321636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:03.898444Z","time spent":"423.075241ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:04.321626Z","caller":"traceutil/trace.go:171","msg":"trace[1304784229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"417.667343ms","start":"2026-06-03T21:43:03.903947Z","end":"2026-06-03T21:43:04.321614Z","steps":["trace[1304784229] 'agreement among raft nodes before linearized reading' (duration: 417.562649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:04.321694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:03.903922Z","time spent":"417.763146ms","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T21:43:04.321413Z","caller":"traceutil/trace.go:171","msg":"trace[147986684] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"417.322671ms","start":"2026-06-03T21:43:03.904041Z","end":"2026-06-03T21:43:04.321364Z","steps":["trace[147986684] 'read index received' (duration: 416.06921ms)","trace[147986684] 'applied index is now lower than readState.Index' (duration: 1.252491ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:43:04.322274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.32648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tsgzk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-03T21:43:04.322369Z","caller":"traceutil/trace.go:171","msg":"trace[1278328806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tsgzk; range_end:; response_count:1; response_revision:651; }","duration":"164.484565ms","start":"2026-06-03T21:43:04.157855Z","end":"2026-06-03T21:43:04.32234Z","steps":["trace[1278328806] 'agreement among raft nodes before linearized reading' (duration: 164.132734ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:04.645011Z","caller":"traceutil/trace.go:171","msg":"trace[415056124] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"309.051606ms","start":"2026-06-03T21:43:04.335894Z","end":"2026-06-03T21:43:04.644945Z","steps":["trace[415056124] 'process raft request' (duration: 308.87803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:04.645325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:04.335863Z","time spent":"309.240532ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:04.655402Z","caller":"traceutil/trace.go:171","msg":"trace[1015923206] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"282.771745ms","start":"2026-06-03T21:43:04.372608Z","end":"2026-06-03T21:43:04.655379Z","steps":["trace[1015923206] 'process raft request' (duration: 282.674642ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:05.672857Z","caller":"traceutil/trace.go:171","msg":"trace[1230238995] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"179.365712ms","start":"2026-06-03T21:43:05.493468Z","end":"2026-06-03T21:43:05.672834Z","steps":["trace[1230238995] 'read index received' (duration: 178.633138ms)","trace[1230238995] 'applied index is now lower than readState.Index' (duration: 731.534µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:43:05.673519Z","caller":"traceutil/trace.go:171","msg":"trace[178720391] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"597.892045ms","start":"2026-06-03T21:43:05.075594Z","end":"2026-06-03T21:43:05.673486Z","steps":["trace[178720391] 'process raft request' (duration: 596.730067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:05.673683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.713462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T21:43:05.673771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:05.075575Z","time spent":"598.027188ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:05.673792Z","caller":"traceutil/trace.go:171","msg":"trace[189011267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"126.896598ms","start":"2026-06-03T21:43:05.546871Z","end":"2026-06-03T21:43:05.673768Z","steps":["trace[189011267] 'agreement among raft nodes before linearized reading' (duration: 126.563577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:05.673908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.312231ms","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-06-03T21:43:05.674091Z","caller":"traceutil/trace.go:171","msg":"trace[1886431110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"180.680244ms","start":"2026-06-03T21:43:05.493384Z","end":"2026-06-03T21:43:05.674064Z","steps":["trace[1886431110] 'agreement among raft nodes before linearized reading' (duration: 180.143506ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:07.634778Z","caller":"traceutil/trace.go:171","msg":"trace[1914673294] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"245.868855ms","start":"2026-06-03T21:43:07.388863Z","end":"2026-06-03T21:43:07.634732Z","steps":["trace[1914673294] 'process raft request' (duration: 245.592386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:22.412359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.060124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:43:22.412819Z","caller":"traceutil/trace.go:171","msg":"trace[1030029648] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"488.459414ms","start":"2026-06-03T21:43:21.924318Z","end":"2026-06-03T21:43:22.412778Z","steps":["trace[1030029648] 'process raft request' (duration: 42.882736ms)","trace[1030029648] 'compare' (duration: 444.94392ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:43:22.413168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:21.924307Z","time spent":"488.588418ms","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:22.413507Z","caller":"traceutil/trace.go:171","msg":"trace[2029400441] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"486.253413ms","start":"2026-06-03T21:43:21.927221Z","end":"2026-06-03T21:43:22.413475Z","steps":["trace[2029400441] 'process raft request' (duration: 485.247946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:22.413685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:21.927208Z","time spent":"486.387076ms","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:22.413798Z","caller":"traceutil/trace.go:171","msg":"trace[1828217304] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"485.82741ms","start":"2026-06-03T21:43:21.927955Z","end":"2026-06-03T21:43:22.413783Z","steps":["trace[1828217304] 'process raft request' (duration: 485.377768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:43:22.414285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:43:21.927936Z","time spent":"486.15898ms","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:43:22.414488Z","caller":"traceutil/trace.go:171","msg":"trace[1074707605] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:848; }","duration":"150.465212ms","start":"2026-06-03T21:43:22.263975Z","end":"2026-06-03T21:43:22.41444Z","steps":["trace[1074707605] 'read index received' (duration: 51.611µs)","trace[1074707605] 'applied index is now lower than readState.Index' (duration: 150.411721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:43:22.414707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.73036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wdlch\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-03T21:43:22.414778Z","caller":"traceutil/trace.go:171","msg":"trace[380544807] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wdlch; range_end:; response_count:1; response_revision:821; }","duration":"150.833552ms","start":"2026-06-03T21:43:22.263929Z","end":"2026-06-03T21:43:22.414763Z","steps":["trace[380544807] 'agreement among raft nodes before linearized reading' (duration: 150.622446ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:22.630689Z","caller":"traceutil/trace.go:171","msg":"trace[1157743358] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"210.91404ms","start":"2026-06-03T21:43:22.419752Z","end":"2026-06-03T21:43:22.630666Z","steps":["trace[1157743358] 'process raft request' (duration: 210.745434ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:26.220888Z","caller":"traceutil/trace.go:171","msg":"trace[584994249] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"119.69978ms","start":"2026-06-03T21:43:26.101153Z","end":"2026-06-03T21:43:26.220853Z","steps":["trace[584994249] 'process raft request' (duration: 117.627325ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:26.475521Z","caller":"traceutil/trace.go:171","msg":"trace[757988864] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"128.740218ms","start":"2026-06-03T21:43:26.346754Z","end":"2026-06-03T21:43:26.475494Z","steps":["trace[757988864] 'process raft request' (duration: 92.292186ms)","trace[757988864] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; req_size:1286; } (duration: 36.334059ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:43:34.367256Z","caller":"traceutil/trace.go:171","msg":"trace[505381241] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"104.60015ms","start":"2026-06-03T21:43:34.262622Z","end":"2026-06-03T21:43:34.367222Z","steps":["trace[505381241] 'process raft request' (duration: 103.701178ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:45.533536Z","caller":"traceutil/trace.go:171","msg":"trace[1636340293] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"113.094343ms","start":"2026-06-03T21:43:45.420418Z","end":"2026-06-03T21:43:45.533512Z","steps":["trace[1636340293] 'process raft request' (duration: 112.916399ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:43:52.837445Z","caller":"traceutil/trace.go:171","msg":"trace[1158925521] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"162.083466ms","start":"2026-06-03T21:43:52.675325Z","end":"2026-06-03T21:43:52.837409Z","steps":["trace[1158925521] 'process raft request' (duration: 161.869542ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:44:35.821831Z","caller":"traceutil/trace.go:171","msg":"trace[840480906] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"142.364908ms","start":"2026-06-03T21:44:35.679439Z","end":"2026-06-03T21:44:35.821803Z","steps":["trace[840480906] 'process raft request' (duration: 142.213656ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:45:04.157199Z","caller":"traceutil/trace.go:171","msg":"trace[753062090] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"162.523334ms","start":"2026-06-03T21:45:03.99465Z","end":"2026-06-03T21:45:04.157173Z","steps":["trace[753062090] 'process raft request' (duration: 90.52851ms)","trace[753062090] 'compare' (duration: 71.906493ms)"],"step_count":2}