{"level":"warn","ts":"2026-04-20T17:42:05.847502Z","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-20T17:42:05.847604Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-20T17:42:05.847684Z","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-20T17:42:05.847698Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T17:42:05.847718Z","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-20T17:42:05.848688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-20T17:42:05.848796Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-20T17:42:05.853897Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.785537ms"} {"level":"info","ts":"2026-04-20T17:42:05.859845Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-20T17:42:05.859993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T17:42:05.860024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-20T17:42:05.860043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T17:42:05.860052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-20T17:42:05.860097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-20T17:42:05.865624Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T17:42:05.86718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T17:42:05.868148Z","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-20T17:42:05.870228Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T17:42:05.870329Z","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-20T17:42:05.870352Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T17:42:05.870417Z","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-20T17:42:05.870466Z","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-20T17:42:05.87397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-20T17:42:05.874377Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T17:42:05.877352Z","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-20T17:42:05.877564Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T17:42:05.877596Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T17:42:05.878392Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T17:42:05.878421Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T17:42:06.66128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T17:42:06.661328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T17:42:06.661358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-20T17:42:06.661372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-20T17:42:06.661381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T17:42:06.661397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-20T17:42:06.661407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T17:42:06.662079Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T17:42:06.662118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:42:06.662435Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:06.66287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T17:42:06.664569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T17:42:06.66809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T17:42:06.668164Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T17:42:06.66909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:06.669262Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:06.669346Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T17:42:06.670914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"warn","ts":"2026-04-20T17:42:08.51126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.421996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-20T17:42:08.511438Z","caller":"traceutil/trace.go:171","msg":"trace[377723077] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"238.413623ms","start":"2026-04-20T17:42:08.272989Z","end":"2026-04-20T17:42:08.511403Z","steps":["trace[377723077] 'process raft request' (duration: 110.321973ms)","trace[377723077] 'compare' (duration: 127.301186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:10.601489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632892927s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-20T17:42:10.601596Z","caller":"traceutil/trace.go:171","msg":"trace[1793055860] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"1.654541545s","start":"2026-04-20T17:42:08.947038Z","end":"2026-04-20T17:42:10.601579Z","steps":["trace[1793055860] 'read index received' (duration: 21.389027ms)","trace[1793055860] 'applied index is now lower than readState.Index' (duration: 1.633151458s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:10.601759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654690146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T17:42:10.601797Z","caller":"traceutil/trace.go:171","msg":"trace[869956655] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:113; }","duration":"1.654803746s","start":"2026-04-20T17:42:08.946983Z","end":"2026-04-20T17:42:10.601786Z","steps":["trace[869956655] 'agreement among raft nodes before linearized reading' (duration: 1.654679495s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.601834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:08.946954Z","time spent":"1.654870987s","remote":"127.0.0.1:51196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-20T17:42:10.601864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654014882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T17:42:10.601901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.23139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-04-20T17:42:10.601968Z","caller":"traceutil/trace.go:171","msg":"trace[1139030364] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:113; }","duration":"1.654169653s","start":"2026-04-20T17:42:08.947755Z","end":"2026-04-20T17:42:10.601925Z","steps":["trace[1139030364] 'agreement among raft nodes before linearized reading' (duration: 1.653988472s)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:10.601978Z","caller":"traceutil/trace.go:171","msg":"trace[1864258059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:113; }","duration":"517.320221ms","start":"2026-04-20T17:42:10.084638Z","end":"2026-04-20T17:42:10.601958Z","steps":["trace[1864258059] 'agreement among raft nodes before linearized reading' (duration: 517.15096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.601993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.71313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T17:42:10.601868Z","caller":"traceutil/trace.go:171","msg":"trace[1191276215] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"2.072340228s","start":"2026-04-20T17:42:08.529288Z","end":"2026-04-20T17:42:10.601628Z","steps":["trace[1191276215] 'process raft request' (duration: 439.2468ms)","trace[1191276215] 'compare' (duration: 1.632760346s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:10.602018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:08.947729Z","time spent":"1.654278523s","remote":"127.0.0.1:51210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-20T17:42:10.602025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.084584Z","time spent":"517.432322ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T17:42:10.602039Z","caller":"traceutil/trace.go:171","msg":"trace[1904737519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"266.79069ms","start":"2026-04-20T17:42:10.335231Z","end":"2026-04-20T17:42:10.602022Z","steps":["trace[1904737519] 'agreement among raft nodes before linearized reading' (duration: 266.71704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.602077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.424631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T17:42:10.602141Z","caller":"traceutil/trace.go:171","msg":"trace[482867992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:113; }","duration":"515.513032ms","start":"2026-04-20T17:42:10.086607Z","end":"2026-04-20T17:42:10.60212Z","steps":["trace[482867992] 'agreement among raft nodes before linearized reading' (duration: 515.367891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.602095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.123801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T17:42:10.602176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.086597Z","time spent":"515.571962ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T17:42:10.602173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:08.529273Z","time spent":"2.07278943s","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:42:10.602226Z","caller":"traceutil/trace.go:171","msg":"trace[662093457] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:113; }","duration":"932.328342ms","start":"2026-04-20T17:42:09.669867Z","end":"2026-04-20T17:42:10.602195Z","steps":["trace[662093457] 'agreement among raft nodes before linearized reading' (duration: 932.047961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.602292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:09.669847Z","time spent":"932.432413ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-20T17:42:10.602501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543115228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T17:42:10.602556Z","caller":"traceutil/trace.go:171","msg":"trace[1421325653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"1.543214408s","start":"2026-04-20T17:42:09.059322Z","end":"2026-04-20T17:42:10.602536Z","steps":["trace[1421325653] 'agreement among raft nodes before linearized reading' (duration: 1.543131138s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:10.602585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:09.059286Z","time spent":"1.543292758s","remote":"127.0.0.1:50776","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-04-20T17:42:11.109267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409862258009361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T17:42:11.462534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.361789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-20T17:42:11.462639Z","caller":"traceutil/trace.go:171","msg":"trace[683141320] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"854.896557ms","start":"2026-04-20T17:42:10.607725Z","end":"2026-04-20T17:42:11.462622Z","steps":["trace[683141320] 'process raft request' (duration: 560.218007ms)","trace[683141320] 'compare' (duration: 294.237488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:11.462698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.607708Z","time spent":"854.963388ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:42:11.572908Z","caller":"traceutil/trace.go:171","msg":"trace[644565606] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"965.082489ms","start":"2026-04-20T17:42:10.607786Z","end":"2026-04-20T17:42:11.572868Z","steps":["trace[644565606] 'process raft request' (duration: 964.396105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:11.57302Z","caller":"traceutil/trace.go:171","msg":"trace[568086087] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"963.783882ms","start":"2026-04-20T17:42:10.609224Z","end":"2026-04-20T17:42:11.573008Z","steps":["trace[568086087] 'process raft request' (duration: 963.56236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:11.573106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.304964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T17:42:11.573127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.60921Z","time spent":"963.852892ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:42:11.572933Z","caller":"traceutil/trace.go:171","msg":"trace[448569278] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"965.110769ms","start":"2026-04-20T17:42:10.607799Z","end":"2026-04-20T17:42:11.572909Z","steps":["trace[448569278] 'process raft request' (duration: 964.897678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:11.573256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.607776Z","time spent":"965.423241ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:42:11.572953Z","caller":"traceutil/trace.go:171","msg":"trace[1146624767] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"965.126609ms","start":"2026-04-20T17:42:10.607798Z","end":"2026-04-20T17:42:11.572924Z","steps":["trace[1146624767] 'process raft request' (duration: 964.949278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:11.573242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.607766Z","time spent":"965.32058ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T17:42:11.573469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.60777Z","time spent":"965.603932ms","remote":"127.0.0.1:51210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:42:11.573003Z","caller":"traceutil/trace.go:171","msg":"trace[642973238] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:118; }","duration":"964.196514ms","start":"2026-04-20T17:42:10.608791Z","end":"2026-04-20T17:42:11.572987Z","steps":["trace[642973238] 'read index received' (duration: 559.166081ms)","trace[642973238] 'applied index is now lower than readState.Index' (duration: 405.029513ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:11.573148Z","caller":"traceutil/trace.go:171","msg":"trace[1726864644] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:118; }","duration":"964.383785ms","start":"2026-04-20T17:42:10.608755Z","end":"2026-04-20T17:42:11.573139Z","steps":["trace[1726864644] 'agreement among raft nodes before linearized reading' (duration: 964.280335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:11.573663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:10.608746Z","time spent":"964.895118ms","remote":"127.0.0.1:51196","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":"warn","ts":"2026-04-20T17:42:11.573991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.881017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T17:42:11.57418Z","caller":"traceutil/trace.go:171","msg":"trace[394170485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"214.113918ms","start":"2026-04-20T17:42:11.360052Z","end":"2026-04-20T17:42:11.574166Z","steps":["trace[394170485] 'agreement among raft nodes before linearized reading' (duration: 213.899797ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:17.377219Z","caller":"traceutil/trace.go:171","msg":"trace[1594024984] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"135.940696ms","start":"2026-04-20T17:42:17.241245Z","end":"2026-04-20T17:42:17.377186Z","steps":["trace[1594024984] 'process raft request' (duration: 111.799761ms)","trace[1594024984] 'compare' (duration: 23.614662ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:17.377779Z","caller":"traceutil/trace.go:171","msg":"trace[458114779] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:342; }","duration":"131.676363ms","start":"2026-04-20T17:42:17.246088Z","end":"2026-04-20T17:42:17.377764Z","steps":["trace[458114779] 'read index received' (duration: 106.973755ms)","trace[458114779] 'applied index is now lower than readState.Index' (duration: 24.701428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:17.378036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.937895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:17.378059Z","caller":"traceutil/trace.go:171","msg":"trace[582621665] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"134.655768ms","start":"2026-04-20T17:42:17.243367Z","end":"2026-04-20T17:42:17.378023Z","steps":["trace[582621665] 'process raft request' (duration: 134.316927ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:17.378085Z","caller":"traceutil/trace.go:171","msg":"trace[1588717934] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:334; }","duration":"132.029166ms","start":"2026-04-20T17:42:17.246042Z","end":"2026-04-20T17:42:17.378071Z","steps":["trace[1588717934] 'agreement among raft nodes before linearized reading' (duration: 131.785784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.231911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409862258010252,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T17:42:37.670329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.727050353s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T17:42:37.670679Z","caller":"traceutil/trace.go:171","msg":"trace[467530346] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.727465189s","start":"2026-04-20T17:42:35.94319Z","end":"2026-04-20T17:42:37.670655Z","steps":["trace[467530346] 'process raft request' (duration: 1.727318833s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.670816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:35.943174Z","time spent":"1.727564813s","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T17:42:37.755681Z","caller":"traceutil/trace.go:171","msg":"trace[179490817] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.762446022s","start":"2026-04-20T17:42:35.993046Z","end":"2026-04-20T17:42:37.755492Z","steps":["trace[179490817] 'process raft request' (duration: 1.736477451s)","trace[179490817] 'compare' (duration: 25.448801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:37.755841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:35.993003Z","time spent":"1.762773235s","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T17:42:37.761303Z","caller":"traceutil/trace.go:171","msg":"trace[1421670296] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.647361369s","start":"2026-04-20T17:42:36.113909Z","end":"2026-04-20T17:42:37.761271Z","steps":["trace[1421670296] 'process raft request' (duration: 1.642781431s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.761465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:36.113881Z","time spent":"1.647505745s","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:42:37.861719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.864956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T17:42:37.861947Z","caller":"traceutil/trace.go:171","msg":"trace[643431670] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"1.131092607s","start":"2026-04-20T17:42:36.7308Z","end":"2026-04-20T17:42:37.861892Z","steps":["trace[643431670] 'read index received' (duration: 939.734199ms)","trace[643431670] 'applied index is now lower than readState.Index' (duration: 191.356678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:37.86205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131214413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:37.862126Z","caller":"traceutil/trace.go:171","msg":"trace[557812716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"1.131346568s","start":"2026-04-20T17:42:36.73076Z","end":"2026-04-20T17:42:37.862107Z","steps":["trace[557812716] 'agreement among raft nodes before linearized reading' (duration: 1.131210263s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.862167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:36.730745Z","time spent":"1.131413351s","remote":"127.0.0.1:50780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T17:42:37.920176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020183531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T17:42:37.920203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.309892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-20T17:42:37.92025Z","caller":"traceutil/trace.go:171","msg":"trace[80893844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"1.020317246s","start":"2026-04-20T17:42:36.899917Z","end":"2026-04-20T17:42:37.920235Z","steps":["trace[80893844] 'agreement among raft nodes before linearized reading' (duration: 1.020151809s)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:37.920278Z","caller":"traceutil/trace.go:171","msg":"trace[1768932560] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:578; }","duration":"121.433567ms","start":"2026-04-20T17:42:37.798824Z","end":"2026-04-20T17:42:37.920257Z","steps":["trace[1768932560] 'agreement among raft nodes before linearized reading' (duration: 121.163866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.920288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:36.899856Z","time spent":"1.020425111s","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T17:42:37.920294Z","caller":"traceutil/trace.go:171","msg":"trace[1103690078] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"104.546679ms","start":"2026-04-20T17:42:37.815722Z","end":"2026-04-20T17:42:37.920268Z","steps":["trace[1103690078] 'process raft request' (duration: 103.994658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.920206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.829442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T17:42:37.920974Z","caller":"traceutil/trace.go:171","msg":"trace[524619381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"179.58497ms","start":"2026-04-20T17:42:37.7413Z","end":"2026-04-20T17:42:37.920885Z","steps":["trace[524619381] 'agreement among raft nodes before linearized reading' (duration: 178.696116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.920535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.604122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T17:42:37.921731Z","caller":"traceutil/trace.go:171","msg":"trace[1520294268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"300.809259ms","start":"2026-04-20T17:42:37.620907Z","end":"2026-04-20T17:42:37.921716Z","steps":["trace[1520294268] 'agreement among raft nodes before linearized reading' (duration: 299.5498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.921842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:37.620897Z","time spent":"300.924283ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T17:42:37.920722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.201292ms","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-20T17:42:37.921911Z","caller":"traceutil/trace.go:171","msg":"trace[1291041240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"379.410429ms","start":"2026-04-20T17:42:37.542474Z","end":"2026-04-20T17:42:37.921884Z","steps":["trace[1291041240] 'agreement among raft nodes before linearized reading' (duration: 378.026805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:37.92199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:37.542455Z","time spent":"379.513713ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T17:42:38.145033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.050287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-20T17:42:38.145209Z","caller":"traceutil/trace.go:171","msg":"trace[1270827145] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:582; }","duration":"166.260955ms","start":"2026-04-20T17:42:37.978922Z","end":"2026-04-20T17:42:38.145183Z","steps":["trace[1270827145] 'range keys from in-memory index tree' (duration: 165.761815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:38.171545Z","caller":"traceutil/trace.go:171","msg":"trace[1766482772] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"156.996517ms","start":"2026-04-20T17:42:38.014527Z","end":"2026-04-20T17:42:38.171524Z","steps":["trace[1766482772] 'process raft request' (duration: 156.789499ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:41.756082Z","caller":"traceutil/trace.go:171","msg":"trace[2128970377] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"188.896284ms","start":"2026-04-20T17:42:41.56715Z","end":"2026-04-20T17:42:41.756046Z","steps":["trace[2128970377] 'process raft request' (duration: 102.398757ms)","trace[2128970377] 'compare' (duration: 86.316221ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:43.933827Z","caller":"traceutil/trace.go:171","msg":"trace[188199832] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"159.485583ms","start":"2026-04-20T17:42:43.774305Z","end":"2026-04-20T17:42:43.933791Z","steps":["trace[188199832] 'process raft request' (duration: 158.897752ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.125547Z","caller":"traceutil/trace.go:171","msg":"trace[570903219] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"138.388537ms","start":"2026-04-20T17:42:43.987137Z","end":"2026-04-20T17:42:44.125526Z","steps":["trace[570903219] 'process raft request' (duration: 138.316875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.125615Z","caller":"traceutil/trace.go:171","msg":"trace[156044564] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"139.57727ms","start":"2026-04-20T17:42:43.985998Z","end":"2026-04-20T17:42:44.125575Z","steps":["trace[156044564] 'process raft request' (duration: 122.467208ms)","trace[156044564] 'compare' (duration: 16.847873ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T17:42:44.125815Z","caller":"traceutil/trace.go:171","msg":"trace[877269157] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"138.455459ms","start":"2026-04-20T17:42:43.98734Z","end":"2026-04-20T17:42:44.125796Z","steps":["trace[877269157] 'process raft request' (duration: 138.152358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.125817Z","caller":"traceutil/trace.go:171","msg":"trace[326427962] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"129.223735ms","start":"2026-04-20T17:42:43.996574Z","end":"2026-04-20T17:42:44.125798Z","steps":["trace[326427962] 'process raft request' (duration: 129.144942ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.270985Z","caller":"traceutil/trace.go:171","msg":"trace[1420521916] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"103.669529ms","start":"2026-04-20T17:42:44.167289Z","end":"2026-04-20T17:42:44.270959Z","steps":["trace[1420521916] 'process raft request' (duration: 101.420628ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.271027Z","caller":"traceutil/trace.go:171","msg":"trace[1083190621] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"101.809932ms","start":"2026-04-20T17:42:44.169181Z","end":"2026-04-20T17:42:44.270991Z","steps":["trace[1083190621] 'process raft request' (duration: 101.733639ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:44.271094Z","caller":"traceutil/trace.go:171","msg":"trace[1469135279] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"103.101898ms","start":"2026-04-20T17:42:44.167977Z","end":"2026-04-20T17:42:44.271079Z","steps":["trace[1469135279] 'process raft request' (duration: 102.836358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:45.124366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.265042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4brhq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T17:42:45.124454Z","caller":"traceutil/trace.go:171","msg":"trace[1294323255] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4brhq; range_end:; response_count:1; response_revision:624; }","duration":"148.392517ms","start":"2026-04-20T17:42:44.976042Z","end":"2026-04-20T17:42:45.124434Z","steps":["trace[1294323255] 'range keys from in-memory index tree' (duration: 148.153859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:45.124521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.197875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:45.124565Z","caller":"traceutil/trace.go:171","msg":"trace[1423506030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"393.278807ms","start":"2026-04-20T17:42:44.731274Z","end":"2026-04-20T17:42:45.124553Z","steps":["trace[1423506030] 'range keys from in-memory index tree' (duration: 393.086681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:45.124597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:44.731253Z","time spent":"393.336289ms","remote":"127.0.0.1:50776","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-04-20T17:42:45.44203Z","caller":"traceutil/trace.go:171","msg":"trace[1213524777] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"303.82133ms","start":"2026-04-20T17:42:45.138181Z","end":"2026-04-20T17:42:45.442002Z","steps":["trace[1213524777] 'process raft request' (duration: 303.602702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:45.442221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:45.138163Z","time spent":"303.955435ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:42:46.06804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.339483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:46.068196Z","caller":"traceutil/trace.go:171","msg":"trace[1284441870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"336.552011ms","start":"2026-04-20T17:42:45.731626Z","end":"2026-04-20T17:42:46.068178Z","steps":["trace[1284441870] 'range keys from in-memory index tree' (duration: 336.2524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:46.068247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:45.731605Z","time spent":"336.630993ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T17:42:46.943001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.27472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T17:42:46.943156Z","caller":"traceutil/trace.go:171","msg":"trace[921076971] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"211.887452ms","start":"2026-04-20T17:42:46.731253Z","end":"2026-04-20T17:42:46.943141Z","steps":["trace[921076971] 'read index received' (duration: 62.012µs)","trace[921076971] 'applied index is now lower than readState.Index' (duration: 211.82463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:46.943221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.979025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:46.943251Z","caller":"traceutil/trace.go:171","msg":"trace[1636034456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"212.051188ms","start":"2026-04-20T17:42:46.731191Z","end":"2026-04-20T17:42:46.943242Z","steps":["trace[1636034456] 'agreement among raft nodes before linearized reading' (duration: 212.000936ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:46.94332Z","caller":"traceutil/trace.go:171","msg":"trace[407570362] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"808.858386ms","start":"2026-04-20T17:42:46.134426Z","end":"2026-04-20T17:42:46.943284Z","steps":["trace[407570362] 'process raft request' (duration: 808.663029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:46.943361Z","caller":"traceutil/trace.go:171","msg":"trace[1404768137] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"808.936979ms","start":"2026-04-20T17:42:46.13435Z","end":"2026-04-20T17:42:46.943287Z","steps":["trace[1404768137] 'process raft request' (duration: 72.308546ms)","trace[1404768137] 'compare' (duration: 736.148666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T17:42:46.943475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:46.134401Z","time spent":"808.994601ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:42:46.943508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:42:46.134333Z","time spent":"809.103264ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T17:42:49.500544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.626483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:49.500604Z","caller":"traceutil/trace.go:171","msg":"trace[397450693] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:662; }","duration":"210.724076ms","start":"2026-04-20T17:42:49.289868Z","end":"2026-04-20T17:42:49.500592Z","steps":["trace[397450693] 'range keys from in-memory index tree' (duration: 210.573382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:49.500893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.887094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:49.500937Z","caller":"traceutil/trace.go:171","msg":"trace[366743386] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:662; }","duration":"114.974128ms","start":"2026-04-20T17:42:49.385951Z","end":"2026-04-20T17:42:49.500925Z","steps":["trace[366743386] 'range keys from in-memory index tree' (duration: 114.773191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:42:49.500897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.85127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T17:42:49.501058Z","caller":"traceutil/trace.go:171","msg":"trace[1700988197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:662; }","duration":"211.050288ms","start":"2026-04-20T17:42:49.289992Z","end":"2026-04-20T17:42:49.501043Z","steps":["trace[1700988197] 'count revisions from in-memory index tree' (duration: 210.747827ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:42:52.343398Z","caller":"traceutil/trace.go:171","msg":"trace[969629001] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"119.895695ms","start":"2026-04-20T17:42:52.223479Z","end":"2026-04-20T17:42:52.343374Z","steps":["trace[969629001] 'process raft request' (duration: 119.481061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.646348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.476755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-swf89\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-20T17:43:02.646416Z","caller":"traceutil/trace.go:171","msg":"trace[1351173379] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-swf89; range_end:; response_count:1; response_revision:779; }","duration":"563.594748ms","start":"2026-04-20T17:43:02.082806Z","end":"2026-04-20T17:43:02.646401Z","steps":["trace[1351173379] 'range keys from in-memory index tree' (duration: 563.306259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.646432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.645272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T17:43:02.646447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:43:02.082789Z","time spent":"563.650729ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-swf89\" "} {"level":"info","ts":"2026-04-20T17:43:02.646482Z","caller":"traceutil/trace.go:171","msg":"trace[1318031717] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:779; }","duration":"384.747244ms","start":"2026-04-20T17:43:02.261722Z","end":"2026-04-20T17:43:02.64647Z","steps":["trace[1318031717] 'range keys from in-memory index tree' (duration: 384.467167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.646519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:43:02.261696Z","time spent":"384.815696ms","remote":"127.0.0.1:50810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-04-20T17:43:02.646719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.590584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T17:43:02.646836Z","caller":"traceutil/trace.go:171","msg":"trace[2068978338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"424.744868ms","start":"2026-04-20T17:43:02.222062Z","end":"2026-04-20T17:43:02.646807Z","steps":["trace[2068978338] 'range keys from in-memory index tree' (duration: 424.230394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.646981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.30234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T17:43:02.647096Z","caller":"traceutil/trace.go:171","msg":"trace[2044886482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"419.494265ms","start":"2026-04-20T17:43:02.227583Z","end":"2026-04-20T17:43:02.647077Z","steps":["trace[2044886482] 'range keys from in-memory index tree' (duration: 418.60596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T17:43:02.647186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:43:02.227573Z","time spent":"419.598219ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T17:43:02.646987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T17:43:02.222049Z","time spent":"424.886453ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T17:43:02.840621Z","caller":"traceutil/trace.go:171","msg":"trace[1788697118] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"187.95971ms","start":"2026-04-20T17:43:02.652624Z","end":"2026-04-20T17:43:02.840584Z","steps":["trace[1788697118] 'process raft request' (duration: 187.825516ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:02.840793Z","caller":"traceutil/trace.go:171","msg":"trace[171421445] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"189.487344ms","start":"2026-04-20T17:43:02.65128Z","end":"2026-04-20T17:43:02.840768Z","steps":["trace[171421445] 'process raft request' (duration: 189.01996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:02.840557Z","caller":"traceutil/trace.go:171","msg":"trace[977334305] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"186.458175ms","start":"2026-04-20T17:43:02.654079Z","end":"2026-04-20T17:43:02.840537Z","steps":["trace[977334305] 'process raft request' (duration: 186.407354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T17:43:45.960738Z","caller":"traceutil/trace.go:171","msg":"trace[119449577] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"155.288821ms","start":"2026-04-20T17:43:45.805423Z","end":"2026-04-20T17:43:45.960711Z","steps":["trace[119449577] 'process raft request' (duration: 155.095587ms)"],"step_count":1}